Result of trunk_asan@ruby-sp1 at 2024-08-02 12:50:03 +0900

• OK (303d2319f0)[LOG]
• test-all: 33698 tests, 6483230 assertions, 0 failures, 0 errors, 372 skips
• test-spec: 3432 files, 32772 examples, 134186 expectations, 0 failures, 0 errors, 0 tagged
• commands:

  "make update-download -j16" exit with 0.
  "make update-src      -j16" exit with 0.
  "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    1035    1035    1035 ?             -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
     1035    1041    1041    1041 pts/0       1041 Ss+   1000   0:00 dash -c cd build-ruby; BUILD_RUBY_WORKING_DIR=/tmp/ruby sh build-loop.sh trunk_asan --incremental
     1041    1042    1041    1041 pts/0       1041 S+    1000   0:00 sh build-loop.sh trunk_asan --incremental
        1  330928    1041    1041 pts/0       1041 S+    1000   0:00 /tmp/ruby/src/trunk_asan/tool/lib/test/unit/parallel.rb: TestRand#test_rand_reseed_on_fork
        1  334512    1041    1041 pts/0       1041 Sl+   1000   0:00 /tmp/ruby/src/trunk_asan/tool/lib/test/unit/parallel.rb: TestAutoload#test_autoload_fork
        1  334516    1041    1041 pts/0       1041 Sl+   1000   0:00 /tmp/ruby/src/trunk_asan/tool/lib/test/unit/parallel.rb: TestAutoload#test_autoload_fork
     1042  433653    1041    1041 pts/0       1041 Sl+   1000   0:00 ruby ./br.rb build_report trunk_asan --incremental
   433653  433656    1041    1041 pts/0       1041 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.20240802-033538 --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
   433656  433657    1041    1041 pts/0       1041 Sl+   1000   0:00 ruby /home/ko1/build-ruby/build-ruby.rb --target_name=trunk_asan --logfile=/home/ko1/ruby/logs/brlog.trunk_asan.20240802-033538 --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
   433657  434568    1041    1041 pts/0       1041 S+    1000   0:00 sh -c make yes-btest TESTOPTS='-q -j16'
   434568  434569    1041    1041 pts/0       1041 S+    1000   0:00 make yes-btest TESTOPTS=-q -j16
   434569  434584    1041    1041 pts/0       1041 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  439040    1041    1041 pts/0       1041 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_1592.rb
   433657  475327    1041    1041 pts/0       1041 R+    1000   0:00 ps jx
        1 2012955 2012955 2012955 ?             -1 Ss    1000   0:00 /usr/lib/systemd/systemd --user
  2012955 2012956 2012955 2012955 ?             -1 S     1000   0:00 (sd-pam)
  2012951 2012987 2012951 2012951 ?             -1 S     1000   0:00 sshd: ko1@pts/1
  2012987 2012990 2012990 2012990 pts/1    2012990 Ss+   1000   0:00 -bash
  2755267 2755359 2755267 2755267 ?             -1 S     1000   0:00 sshd: ko1@pts/2
  2755359 2755380 2755380 2755380 pts/2    2755380 Ss+   1000   0:00 -bash
  {1=>[1035, 330928, 334512, 334516, 439040, 2012955], 1035=>[1041], 1041=>[1042], 1042=>[433653], 433653=>[433656], 433656=>[433657], 433657=>[434568, 475327], 434568=>[434569], 434569=>[434584], 2012955=>[2012956], 2012951=>[2012987], 2012987=>[2012990], 2755267=>[2755359], 2755359=>[2755380]}
  $ timeout 60 gdb -p 434584 -x /home/ko1/build-ruby/gdbscript -batch -quiet 2> /dev/null
  [New LWP 434610]
  [New LWP 434589]
  [Thread debugging using libthread_db enabled]
  Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
  0x0000734449d1ba00 in __GI_ppoll (fds=0x7ffe321eb828, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:42
  >>> Threads
    Id   Target Id                                          Frame 
  * 1    Thread 0x73444a412740 (LWP 434584) "ruby"          0x0000734449d1ba00 in __GI_ppoll (fds=0x7ffe321eb828, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:42
    2    Thread 0x73443a0006c0 (LWP 434610) "runner.rb:533" 0x0000734449d1b4cd in __GI___poll (fds=0x734439eff180, nfds=1, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
    3    Thread 0x73443b8006c0 (LWP 434589) "runner.rb:322" 0x0000734449c98d61 in __futex_abstimed_wait_common64 (private=29508, cancel=true, abstime=0x0, op=393, expected=0, futex_word=0x6192a88b1fa8) at ./nptl/futex-internal.c:57
  >>> Machine level backtrace
  Thread 3 (Thread 0x73443b8006c0 (LWP 434589) "runner.rb:322"):
  #0  0x0000734449c98d61 in __futex_abstimed_wait_common64 (private=29508, cancel=true, abstime=0x0, op=393, expected=0, futex_word=0x6192a88b1fa8) 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=29508, abstime=0x0, clockid=0, expected=0, futex_word=0x6192a88b1fa8) 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=0x6192a88b1fa8, 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  0x0000734449c9b7dd in __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x6192a895d020, cond=0x6192a88b1f80) at ./nptl/pthread_cond_wait.c:503
          spin = 0
          buffer = {__routine = 0x734449c9b4a0 <__condvar_cleanup_waiting>, __arg = 0x73443b6ff370, __canceltype = 1217323724, __prev = 0x0}
          cbuffer = {wseq = 200, cond = 0x6192a88b1f80, mutex = 0x6192a895d020, private = 0}
          err = <optimized out>
          g = 0
          flags = <optimized out>
          g1_start = <optimized out>
          maxspin = 0
          signals = <optimized out>
          result = 0
          wseq = 200
          seq = 100
          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=0x6192a88b1f80, mutex=0x6192a895d020) at ./nptl/pthread_cond_wait.c:627
  No locals.
  #5  0x000073444a20fced in rb_native_cond_wait () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #6  0x000073444a21189c in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #7  0x000073444a211992 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #8  0x000073444a21679c in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #9  0x000073444a0b7440 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #10 0x000073444a20bf21 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #11 0x000073444a20bfcb in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #12 0x000073444a24d8f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #13 0x000073444a2500ff in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #14 0x000073444a2531c2 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #15 0x000073444a257b3a in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #16 0x000073444a25c7ae in rb_vm_invoke_proc () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #17 0x000073444a214a6f in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #18 0x000073444a214f61 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #19 0x000073444a215836 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #20 0x0000734449c9ca94 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 = {126736893216448, -741119800618464869, 126736893216448, -136, 22, 140729739290608, -741119800563938917, -740870606868002405}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}}
          not_first_call = <optimized out>
  #21 0x0000734449d29c3c in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78
  No locals.
  Thread 2 (Thread 0x73443a0006c0 (LWP 434610) "runner.rb:533"):
  #0  0x0000734449d1b4cd in __GI___poll (fds=0x734439eff180, nfds=1, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
          sc_ret = -516
          sc_cancel_oldtype = 0
          sc_ret = <optimized out>
  #1  0x000073444a0e763f in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #2  0x000073444a0e85d9 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #3  0x000073444a214563 in rb_thread_io_blocking_region () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #4  0x000073444a0e89be in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #5  0x000073444a0edbf9 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #6  0x000073444a0b7440 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #7  0x000073444a0e90f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #8  0x000073444a0ed0f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #9  0x000073444a0f0626 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #10 0x000073444a24d8f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #11 0x000073444a2500ff in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #12 0x000073444a2531c2 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #13 0x000073444a257b3a in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #14 0x000073444a25c7ae in rb_vm_invoke_proc () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #15 0x000073444a214a6f in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #16 0x000073444a214f61 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #17 0x000073444a215836 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #18 0x0000734449c9ca94 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 = {126736868050624, -741116502083581541, 126736868050624, -136, 25, 126736988631584, -741116502029055589, -740870606868002405}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}}
          not_first_call = <optimized out>
  #19 0x0000734449d29c3c in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78
  No locals.
  Thread 1 (Thread 0x73444a412740 (LWP 434584) "ruby"):
  #0  0x0000734449d1ba00 in __GI_ppoll (fds=0x7ffe321eb828, 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 = 140729739294960, tv_nsec = 126737138643225}
  #1  0x000073444a2109e9 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #2  0x000073444a211aa1 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #3  0x000073444a2142c2 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #4  0x000073444a215a65 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #5  0x000073444a0b7440 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #6  0x000073444a210eab in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #7  0x000073444a253428 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #8  0x000073444a257b3a in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #9  0x000073444a25c287 in rb_yield () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #10 0x000073444a0b7440 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #11 0x000073444a0952a6 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #12 0x000073444a24d8f0 in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #13 0x000073444a2500ff in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #14 0x000073444a2545dc in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #15 0x000073444a257b3a in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #16 0x000073444a0b4e5c in ?? () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #17 0x000073444a0b9193 in ruby_run_node () from /lib/x86_64-linux-gnu/libruby-3.2.so.3.2
  No symbol table info available.
  #18 0x00006192a7140187 in ?? ()
  No symbol table info available.
  #19 0x0000734449c2a1ca in __libc_start_call_main (main=main@entry=0x6192a7140120, argc=argc@entry=6, argv=argv@entry=0x7ffe321ec6f8) at ../sysdeps/nptl/libc_start_call_main.h:58
          self = <optimized out>
          result = <optimized out>
          unwind_buf = {cancel_jmp_buf = {{jmp_buf = {140729739298552, -1386244788023408229, 6, 0, 107282496236864, 126737141932032, -1386244788109391461, -740870668261602917}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x6, 0x7ffe321ec6f0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 6}}}
          not_first_call = <optimized out>
  #20 0x0000734449c2a28b in __libc_start_main_impl (main=0x6192a7140120, argc=6, argv=0x7ffe321ec6f8, init=<optimized out>, fini=<optimized out>, rtld_fini=<optimized out>, stack_end=0x7ffe321ec6e8) at ../csu/libc-start.c:360
  No locals.
  #21 0x00006192a71401d5 in _start ()
  No symbol table info available.
  >>> Dump Ruby level backtrace (if possible)
  [Inferior 1 (process 434584) detached]
  $ timeout 60 gdb -p 434569 -x /home/ko1/build-ruby/gdbscript -batch -quiet 2> /dev/null
   make: *** [uncommon.mk:897: yes-btest] Killed
  >>> Threads
  No threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace (if possible)
  #<Errno::ESRCH: No such process>
  $ timeout 60 gdb -p 434568 -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 475327 -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.20240802-033538
  #<BuildRuby:0x00007efd653cfdc0
   @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.20240802-033538",
   @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.000018   0.000005   0.000023 (  0.000024)
  autoconf               0.000033   0.000010   0.000043 (  0.000043)
  configure              0.000026   0.000008   0.000034 (  0.000032)
  build_up               0.001262   0.003940   1.583221 (  1.837184)
  build_miniruby         0.000746   0.000287   0.768252 (  0.766169)
  build_ruby             0.001205   0.000092   1.494597 (  1.493210)
  build_all              0.003664   0.001855  18.591104 (  2.239050)
  build_install          0.007172   0.004429  20.610986 (  3.691013)
  test_btest             0.004875   0.001950 429.954372 (600.283889)
  test_basic             0.004136   0.001063   1.082417 (  1.140232)
  test_all               0.146011   0.050347 1886.302742 (206.694149)
  test_rubyspec          0.058532   0.057960 553.599129 ( 46.869000)
  total: 865.01 sec