Result of trunk_asan@ruby-sp1 at 2024-07-19 12:26:54 +0900

• OK (3222c67262)[LOG]
• test-all: 33497 tests, 6465969 assertions, 0 failures, 0 errors, 358 skips
• test-spec: 3432 files, 32772 examples, 131919 expectations, 0 failures, 0 errors, 0 tagged
• commands:

  "make update-download -j16" exit with 0.
  "make update-src      -j16" exit with 0.
  From https://github.com/ruby/ruby
     1c81d1a..3222c67  master     -> origin/master
  "make after-update    -j16" exit with 0.
  "make miniruby -j16" exit with 0.
  "make ruby -j16" exit with 0.
  "make all -j16" exit with 0.
  "make install -j16" exit with 0.
  "make yes-btest TESTOPTS='-q -j16'" exit with 512.
  $$$ #<Timeout::Error: execution expired>
  ### enter analyzing mode for stuck processes
  $$$ ps jx
     PPID     PID    PGID     SID TTY        TPGID STAT   UID   TIME COMMAND
        1     947     947     947 ?             -1 Ss    1000   0:01 SCREEN -d -m dash -c cd build-ruby; BUILD_RUBY_WORKING_DIR=/tmp/ruby sh build-loop.sh trunk_asan --incremental
      947     957     957     957 pts/0        957 Ss+   1000   0:00 dash -c cd build-ruby; BUILD_RUBY_WORKING_DIR=/tmp/ruby sh build-loop.sh trunk_asan --incremental
      957     958     957     957 pts/0        957 S+    1000   0:00 sh build-loop.sh trunk_asan --incremental
        1  924644     957     957 pts/0        957 Rl+   1000 3499:02 /tmp/ruby/build/trunk_asan/ruby
        1 1778585     957     957 pts/0        957 Sl+   1000   0:00 /tmp/ruby/src/trunk_asan/tool/lib/test/unit/parallel.rb: TestAutoload#test_autoload_fork
      958 1790260     957     957 pts/0        957 Sl+   1000   0:00 ruby ./br.rb build_report trunk_asan --incremental
  1790260 1790263     957     957 pts/0        957 S+    1000   0:00 sh -c ruby /home/ko1/build-ruby/build-ruby.rb --target_name=trunk_asan --logfile=/home/ko1/ruby/logs/brlog.trunk_asan.20240719-031223 --root_dir=/tmp/ruby --timeout=600 --no-timeout-error --configure_opts="'CC=clang-17' 'cflags=-fsanitize=address -fno-omit-frame-pointer -DUSE_MN_THREADS=0'" --incremental
  1790263 1790264     957     957 pts/0        957 Sl+   1000   0:00 ruby /home/ko1/build-ruby/build-ruby.rb --target_name=trunk_asan --logfile=/home/ko1/ruby/logs/brlog.trunk_asan.20240719-031223 --root_dir=/tmp/ruby --timeout=600 --no-timeout-error --configure_opts='CC=clang-17' 'cflags=-fsanitize=address -fno-omit-frame-pointer -DUSE_MN_THREADS=0' --incremental
  1790264 1791184     957     957 pts/0        957 S+    1000   0:00 sh -c make yes-btest TESTOPTS='-q -j16'
  1791184 1791185     957     957 pts/0        957 S+    1000   0:00 make yes-btest TESTOPTS=-q -j16
  1791185 1791200     957     957 pts/0        957 Sl+   1000   0:03 /usr/bin/ruby --disable=gems /tmp/ruby/src/trunk_asan/bootstraptest/runner.rb --ruby=./miniruby -I/tmp/ruby/src/trunk_asan/lib -I. -I.ext/common  -r./x86_64-linux-fake --disable-gems -q -j16
        1 1798273     957     957 pts/0        957 Sl+   1000   0:00 /tmp/ruby/build/trunk_asan/miniruby -I/tmp/ruby/src/trunk_asan/lib -I/tmp/ruby/build/trunk_asan -I/tmp/ruby/build/trunk_asan/.ext/common -r/tmp/ruby/build/trunk_asan/x86_64-linux-fake --disable-gems -W0 bootstraptest.test_thread.rb_449_1586.rb
  1790264 1831932     957     957 pts/0        957 R+    1000   0:00 ps jx
        1 2109098 2109098 2109098 ?             -1 Ss    1000   0:00 /usr/lib/systemd/systemd --user
  2109098 2109100 2109098 2109098 ?             -1 S     1000   0:00 (sd-pam)
  2109044 2109233 2109044 2109044 ?             -1 S     1000   0:00 sshd: ko1@pts/1
  2109233 2109240 2109240 2109240 pts/1    2109240 Ss+   1000   0:00 -bash
  {1=>[947, 924644, 1778585, 1798273, 2109098], 947=>[957], 957=>[958], 958=>[1790260], 1790260=>[1790263], 1790263=>[1790264], 1790264=>[1791184, 1831932], 1791184=>[1791185], 1791185=>[1791200], 2109098=>[2109100], 2109044=>[2109233], 2109233=>[2109240]}
  $ timeout 60 gdb -p 1791200 -x /home/ko1/build-ruby/gdbscript -batch -quiet 2> /dev/null
  [New LWP 1791224]
  [New LWP 1791218]
  [Thread debugging using libthread_db enabled]
  Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
  0x0000762710f1ba00 in __GI_ppoll (fds=0x7ffdd60afa38, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:42
  >>> Threads
    Id   Target Id                                           Frame 
  * 1    Thread 0x762711686740 (LWP 1791200) "ruby"          0x0000762710f1ba00 in __GI_ppoll (fds=0x7ffdd60afa38, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:42
    2    Thread 0x762701a006c0 (LWP 1791224) "runner.rb:519" 0x0000762710f1b4cd in __GI___poll (fds=0x7627018ff180, nfds=1, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
    3    Thread 0x7627032006c0 (LWP 1791218) "runner.rb:322" 0x0000762710e98d61 in __futex_abstimed_wait_common64 (private=30247, cancel=true, abstime=0x0, op=393, expected=0, futex_word=0x5c09f100da48) at ./nptl/futex-internal.c:57
  >>> Machine level backtrace
  Thread 3 (Thread 0x7627032006c0 (LWP 1791218) "runner.rb:322"):
  #0  0x0000762710e98d61 in __futex_abstimed_wait_common64 (private=30247, cancel=true, abstime=0x0, op=393, expected=0, futex_word=0x5c09f100da48) at ./nptl/futex-internal.c:57
          sc_cancel_oldtype = 0
          sc_ret = <optimized out>
          resultvar = <optimized out>
          __arg6 = <optimized out>
          __arg5 = <optimized out>
          __arg4 = <optimized out>
          __arg3 = <optimized out>
          __arg2 = <optimized out>
          __arg1 = <optimized out>
          _a6 = <optimized out>
          _a5 = <optimized out>
          _a4 = <optimized out>
          _a3 = <optimized out>
          _a2 = <optimized out>
          _a1 = <optimized out>
  #1  __futex_abstimed_wait_common (cancel=true, private=30247, abstime=0x0, clockid=0, expected=0, futex_word=0x5c09f100da48) at ./nptl/futex-internal.c:87
          err = <optimized out>
          clockbit = 256
          op = 393
          err = <optimized out>
          clockbit = <optimized out>
          op = <optimized out>
  #2  __GI___futex_abstimed_wait_cancelable64 (futex_word=futex_word@entry=0x5c09f100da48, expected=expected@entry=0, clockid=clockid@entry=0, abstime=abstime@entry=0x0, private=private@entry=0) at ./nptl/futex-internal.c:139
  No locals.
  #3  0x0000762710e9b7dd in __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x5c09f102c480, cond=0x5c09f100da20) at ./nptl/pthread_cond_wait.c:503
          spin = 0
          buffer = {__routine = 0x762710e9b4a0 <__condvar_cleanup_waiting>, __arg = 0x7627030ff370, __canceltype = 263708740, __prev = 0x0}
          cbuffer = {wseq = 504, cond = 0x5c09f100da20, mutex = 0x5c09f102c480, private = 0}
          err = <optimized out>
          g = 0
          flags = <optimized out>
          g1_start = <optimized out>
          maxspin = 0
          signals = <optimized out>
          result = 0
          wseq = 504
          seq = 252
          private = 0
          maxspin = <optimized out>
          err = <optimized out>
          result = <optimized out>
          wseq = <optimized out>
          g = <optimized out>
          seq = <optimized out>
          flags = <optimized out>
          private = <optimized out>
          signals = <optimized out>
          done = <optimized out>
          g1_start = <optimized out>
          spin = <optimized out>
          buffer = <optimized out>
          cbuffer = <optimized out>
          s = <optimized out>
  #4  ___pthread_cond_wait (cond=0x5c09f100da20, mutex=0x5c09f102c480) at ./nptl/pthread_cond_wait.c:627
  No locals.
  #5  0x000076271140fced in rb_native_cond_wait () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #6  0x000076271141189c in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #7  0x0000762711411992 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #8  0x000076271141679c in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #9  0x00007627112b7440 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #10 0x000076271140bf21 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #11 0x000076271140bfcb in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #12 0x000076271144d8f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #13 0x00007627114500ff in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #14 0x00007627114531c2 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #15 0x0000762711457b3a in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #16 0x000076271145c7ae in rb_vm_invoke_proc () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #17 0x0000762711414a6f in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #18 0x0000762711414f61 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #19 0x0000762711415836 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #20 0x0000762710e9ca94 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:447
          ret = <optimized out>
          pd = <optimized out>
          out = <optimized out>
          unwind_buf = {cancel_jmp_buf = {{jmp_buf = {129909928232640, 1827207050354066249, 129909928232640, -136, 22, 140728194492928, 1827207050299540297, 1827244074295067465}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}}
          not_first_call = <optimized out>
  #21 0x0000762710f29c3c in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78
  No locals.
  Thread 2 (Thread 0x762701a006c0 (LWP 1791224) "runner.rb:519"):
  #0  0x0000762710f1b4cd in __GI___poll (fds=0x7627018ff180, nfds=1, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
          sc_ret = -516
          sc_cancel_oldtype = 0
          sc_ret = <optimized out>
  #1  0x00007627112e763f in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #2  0x00007627112e85d9 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #3  0x0000762711414563 in rb_thread_io_blocking_region () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #4  0x00007627112e89be in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #5  0x00007627112edbf9 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #6  0x00007627112b7440 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #7  0x00007627112e90f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #8  0x00007627112ed0f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #9  0x00007627112f0626 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #10 0x000076271144d8f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #11 0x00007627114500ff in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #12 0x00007627114531c2 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #13 0x0000762711457b3a in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #14 0x000076271145c7ae in rb_vm_invoke_proc () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #15 0x0000762711414a6f in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #16 0x0000762711414f61 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #17 0x0000762711415836 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #18 0x0000762710e9ca94 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:447
          ret = <optimized out>
          pd = <optimized out>
          out = <optimized out>
          unwind_buf = {cancel_jmp_buf = {{jmp_buf = {129909903066816, 1827210348888949577, 129909903066816, -136, 25, 129910023647776, 1827210348834423625, 1827244074295067465}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}}
          not_first_call = <optimized out>
  #19 0x0000762710f29c3c in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78
  No locals.
  Thread 1 (Thread 0x762711686740 (LWP 1791200) "ruby"):
  #0  0x0000762710f1ba00 in __GI_ppoll (fds=0x7ffdd60afa38, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:42
          sc_ret = -514
          sc_cancel_oldtype = 0
          sc_ret = <optimized out>
          tval = {tv_sec = 140728194497280, tv_nsec = 129910165270809}
  #1  0x00007627114109e9 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #2  0x0000762711411aa1 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #3  0x00007627114142c2 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #4  0x0000762711415a65 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #5  0x00007627112b7440 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #6  0x0000762711410eab in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #7  0x0000762711453428 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #8  0x0000762711457b3a in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #9  0x000076271145c287 in rb_yield () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #10 0x00007627112b7440 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #11 0x00007627112952a6 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #12 0x000076271144d8f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #13 0x00007627114500ff in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #14 0x00007627114545dc in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #15 0x0000762711457b3a in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #16 0x00007627112b4e5c in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #17 0x00007627112b9193 in ruby_run_node () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #18 0x00005c09efbc6187 in ?? ()
  No symbol table info available.
  #19 0x0000762710e2a1ca in __libc_start_call_main (main=main@entry=0x5c09efbc6120, argc=argc@entry=6, argv=argv@entry=0x7ffdd60b0908) at ../sysdeps/nptl/libc_start_call_main.h:58
          self = <optimized out>
          result = <optimized out>
          unwind_buf = {cancel_jmp_buf = {{jmp_buf = {140728194500872, 787603304968454985, 6, 0, 101197746572608, 129910169034752, 787603304932803401, 1827244131006776137}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x6, 0x7ffdd60b0900}, data = {prev = 0x0, cleanup = 0x0, canceltype = 6}}}
          not_first_call = <optimized out>
  #20 0x0000762710e2a28b in __libc_start_main_impl (main=0x5c09efbc6120, argc=6, argv=0x7ffdd60b0908, init=<optimized out>, fini=<optimized out>, rtld_fini=<optimized out>, stack_end=0x7ffdd60b08f8) at ../csu/libc-start.c:360
  No locals.
  #21 0x00005c09efbc61d5 in _start ()
  No symbol table info available.
  >>> Dump Ruby level backtrace (if possible)
  [Inferior 1 (process 1791200) detached]
  $ timeout 60 gdb -p 1791185 -x /home/ko1/build-ruby/gdbscript -batch -quiet 2> /dev/null
   make: *** [uncommon.mk:898: yes-btest] Killed
  >>> Threads
  No threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace (if possible)
  #<Errno::ESRCH: No such process>
  $ timeout 60 gdb -p 1791184 -x /home/ko1/build-ruby/gdbscript -batch -quiet 2> /dev/null
  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
  >>> Threads
  No threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace (if possible)
  $ timeout 60 gdb -p 1831932 -x /home/ko1/build-ruby/gdbscript -batch -quiet 2> /dev/null
  >>> Threads
  No threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace (if possible)
  #<Errno::ESRCH: No such process>
  "make yes-test-basic TESTS='-j16'" exit with 0.
  "make yes-test-all TESTOPTS='--stderr-on-failure' TESTS='-j16'" exit with 0.
  "make yes-test-rubyspec MSPECOPT='--error-output stderr -j' TESTS='-j16'" exit with 0.
  

• Linux ruby-sp1 6.8.0-38-generic #38-Ubuntu SMP PREEMPT_DYNAMIC Fri Jun  7 15:25:01 UTC 2024 x86_64 x86_64 x86_64 GNU/Linux
  

• Logfile: /home/ko1/ruby/logs/brlog.trunk_asan.20240719-031223
  #<BuildRuby:0x000075abfdabfdc8
   @BUILD_DIR="/tmp/ruby/build",
   @INSTALL_DIR="/tmp/ruby/install",
   @REPOSITORY="https://github.com/ruby/ruby.git",
   @REPOSITORY_TYPE=:git,
   @SRC_DIR="/tmp/ruby/src",
   @TARGET_BUILD_DIR="/tmp/ruby/build/trunk_asan",
   @TARGET_INSTALL_DIR="/tmp/ruby/install/trunk_asan",
   @TARGET_NAME="trunk_asan",
   @TARGET_SRC_DIR="/tmp/ruby/src/trunk_asan",
   @btest_opts="-j16",
   @build_opts="-j16",
   @configure_opts=
    ["'CC=clang-17' 'cflags=-fsanitize=address -fno-omit-frame-pointer -DUSE_MN_THREADS=0'"],
   @gist=false,
   @git_branch=nil,
   @git_worktree=nil,
   @incremental=true,
   @logfile="/home/ko1/ruby/logs/brlog.trunk_asan.20240719-031223",
   @make="make",
   @no_timeout_error=true,
   @quiet=false,
   @ruby_env=[["RUBY_DEBUG", "ci"]],
   @spec_opts="-j",
   @steps=
    ["checkout",
     "autoconf",
     "configure",
     "build_up",
     "build_miniruby",
     "build_ruby",
     "build_all",
     "build_install",
     "test_btest",
     "test_basic",
     "test_all",
     "test_rubyspec"],
   @svn_revision=nil,
   @test_opts="TESTS='-j16'",
   @timeout=600>
                             user     system      total        real
  checkout               0.000007   0.000002   0.000009 (  0.000009)
  autoconf               0.000011   0.000004   0.000015 (  0.000016)
  configure              0.000009   0.000003   0.000012 (  0.000012)
  build_up               0.001976   0.003996   1.723104 (  2.039619)
  build_miniruby         0.000000   0.001311   0.811029 (  0.809143)
  build_ruby             0.000147   0.001218   1.560843 (  1.559142)
  build_all              0.003665   0.001838  17.159380 (  2.241906)
  build_install          0.007380   0.005166  19.427327 (  4.080700)
  test_btest             0.004841   0.001925 421.565700 (600.320561)
  test_basic             0.001116   0.004885   1.177964 (  1.232139)
  test_all               0.142019   0.052602 1942.777201 (208.792305)
  test_rubyspec          0.052298   0.059784 541.129677 ( 49.489362)
  total: 870.57 sec