Project

Profile

Help

Bug #5925 » profile20230317.txt

Profile - Mary Holstege, 2023-03-17 18:01

 
Statistical profiling result from isolate-0x563569dff7a0-115741-v8.log, (74299 ticks, 9 unaccounted, 0 excluded).

[Shared libraries]:
ticks total nonlib name
56310 75.8% /usr/lib64/libnode.so.93
14 0.0% /usr/lib64/libstdc++.so.6.0.30
5 0.0% [vdso]
1 0.0% /usr/lib64/libuv.so.1.0.0

[JavaScript]:
ticks total nonlib name
164 0.2% 0.9% LazyCompile: *I /home/mary/node_modules/saxon-js/SaxonJS2N.js:4998:300
99 0.1% 0.6% LazyCompile: *K /home/mary/node_modules/saxon-js/SaxonJS2N.js:5001:385
79 0.1% 0.4% LazyCompile: *getFnSignature /home/mary/node_modules/saxon-js/SaxonJS2N.js:4936:508
75 0.1% 0.4% LazyCompile: *S /home/mary/node_modules/saxon-js/SaxonJS2N.js:5001:246
48 0.1% 0.3% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:5003:417
32 0.0% 0.2% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4999:56
27 0.0% 0.2% LazyCompile: *F /home/mary/node_modules/saxon-js/SaxonJS2N.js:4998:256
24 0.0% 0.1% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:5001:144
21 0.0% 0.1% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:5001:20
11 0.0% 0.1% RegExp: ^Q\{
7 0.0% 0.0% LazyCompile: *z /home/mary/node_modules/saxon-js/SaxonJS2N.js:4598:435
6 0.0% 0.0% LazyCompile: *c /home/mary/node_modules/saxon-js/SaxonJS2N.js:5008:150
6 0.0% 0.0% LazyCompile: *V /home/mary/node_modules/saxon-js/SaxonJS2N.js:5001:97
6 0.0% 0.0% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4992:207
5 0.0% 0.0% LazyCompile: *A /home/mary/node_modules/saxon-js/SaxonJS2N.js:4998:164
5 0.0% 0.0% LazyCompile: *A /home/mary/node_modules/saxon-js/SaxonJS2N.js:4599:377
5 0.0% 0.0% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:5001:329
4 0.0% 0.0% LazyCompile: *e /home/mary/node_modules/saxon-js/SaxonJS2N.js:4236:292
4 0.0% 0.0% LazyCompile: *b /home/mary/node_modules/saxon-js/SaxonJS2N.js:4236:184
4 0.0% 0.0% Function: ^I /home/mary/node_modules/saxon-js/SaxonJS2N.js:4998:300
3 0.0% 0.0% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4642:336
3 0.0% 0.0% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4618:376
3 0.0% 0.0% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4594:56
2 0.0% 0.0% RegExp: ^A(S|Z$|U$)
2 0.0% 0.0% LazyCompile: *kc.next /home/mary/node_modules/saxon-js/SaxonJS2N.js:4211:27
2 0.0% 0.0% LazyCompile: *jc.next /home/mary/node_modules/saxon-js/SaxonJS2N.js:4211:372
2 0.0% 0.0% LazyCompile: *a /home/mary/node_modules/saxon-js/SaxonJS2N.js:4236:69
2 0.0% 0.0% LazyCompile: *J /home/mary/node_modules/saxon-js/SaxonJS2N.js:4591:267
2 0.0% 0.0% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4619:252
2 0.0% 0.0% Function: ^getFnSignature /home/mary/node_modules/saxon-js/SaxonJS2N.js:4936:508
2 0.0% 0.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4673:477
1 0.0% 0.0% RegExp: ^A[DFO]
1 0.0% 0.0% LazyCompile: *yb /home/mary/node_modules/saxon-js/SaxonJS2N.js:4126:389
1 0.0% 0.0% LazyCompile: *xc /home/mary/node_modules/saxon-js/SaxonJS2N.js:4218:227
1 0.0% 0.0% LazyCompile: *g.b /home/mary/node_modules/saxon-js/SaxonJS2N.js:4255:266
1 0.0% 0.0% LazyCompile: *forAllPairs /home/mary/node_modules/saxon-js/SaxonJS2N.js:4239:209
1 0.0% 0.0% LazyCompile: *f /home/mary/node_modules/saxon-js/SaxonJS2N.js:4592:391
1 0.0% 0.0% LazyCompile: *ca.next /home/mary/node_modules/saxon-js/SaxonJS2N.js:4207:345
1 0.0% 0.0% LazyCompile: *ca.hashCode /home/mary/node_modules/saxon-js/SaxonJS2N.js:4128:166
1 0.0% 0.0% LazyCompile: *Eb /home/mary/node_modules/saxon-js/SaxonJS2N.js:4127:277
1 0.0% 0.0% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4672:381
1 0.0% 0.0% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4670:270
1 0.0% 0.0% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4619:492
1 0.0% 0.0% Function: ^w /home/mary/node_modules/saxon-js/SaxonJS2N.js:4602:20
1 0.0% 0.0% Function: ^string /home/mary/node_modules/saxon-js/SaxonJS2N.js:4558:16
1 0.0% 0.0% Function: ^r /home/mary/node_modules/saxon-js/SaxonJS2N.js:4238:82
1 0.0% 0.0% Function: ^m /home/mary/node_modules/saxon-js/SaxonJS2N.js:4294:483
1 0.0% 0.0% Function: ^k /home/mary/node_modules/saxon-js/SaxonJS2N.js:4593:402
1 0.0% 0.0% Function: ^g.b /home/mary/node_modules/saxon-js/SaxonJS2N.js:4255:266
1 0.0% 0.0% Function: ^f /home/mary/node_modules/saxon-js/SaxonJS2N.js:4592:391
1 0.0% 0.0% Function: ^concat /home/mary/node_modules/saxon-js/SaxonJS2N.js:4508:163
1 0.0% 0.0% Function: ^ca.expand /home/mary/node_modules/saxon-js/SaxonJS2N.js:4209:341
1 0.0% 0.0% Function: ^ca.compareTo /home/mary/node_modules/saxon-js/SaxonJS2N.js:4128:22
1 0.0% 0.0% Function: ^b /home/mary/node_modules/saxon-js/SaxonJS2N.js:4236:184
1 0.0% 0.0% Function: ^Td /home/mary/node_modules/saxon-js/SaxonJS2N.js:4244:431
1 0.0% 0.0% Function: ^S /home/mary/node_modules/saxon-js/SaxonJS2N.js:5001:246
1 0.0% 0.0% Function: ^A /home/mary/node_modules/saxon-js/SaxonJS2N.js:4599:377
1 0.0% 0.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:5003:201
1 0.0% 0.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:5001:329
1 0.0% 0.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:5001:144
1 0.0% 0.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4649:311
1 0.0% 0.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4620:302
1 0.0% 0.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4611:313
1 0.0% 0.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4611:289
1 0.0% 0.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4588:414

[C++]:
ticks total nonlib name
11758 15.8% 65.4% __futex_abstimed_wait_common
2542 3.4% 14.1% __strlen_avx2
694 0.9% 3.9% __memcpy_avx_unaligned_erms
267 0.4% 1.5% __GI___munmap
226 0.3% 1.3% __GI___libc_malloc
216 0.3% 1.2% _int_malloc
197 0.3% 1.1% node::contextify::ContextifyContext::CompileFunction(v8::FunctionCallbackInfo<v8::Value> const&)
182 0.2% 1.0% __GI___libc_write
160 0.2% 0.9% __GI___mmap
130 0.2% 0.7% __GI___lll_lock_wake
107 0.1% 0.6% node::native_module::NativeModuleEnv::CompileFunction(v8::FunctionCallbackInfo<v8::Value> const&)
94 0.1% 0.5% __GI___lll_lock_wait
93 0.1% 0.5% __GI___pthread_mutex_lock
87 0.1% 0.5% __GI___mprotect
76 0.1% 0.4% _int_free
68 0.1% 0.4% unlink_chunk.constprop.0
42 0.1% 0.2% __GI___pthread_mutex_unlock_usercnt
34 0.0% 0.2% __tls_get_addr
34 0.0% 0.2% __GI___lll_lock_wake_private
33 0.0% 0.2% epoll_pwait
28 0.0% 0.2% cfree@GLIBC_2.2.5
25 0.0% 0.1% fputc
23 0.0% 0.1% __GI___pthread_cond_signal
16 0.0% 0.1% __GI___lll_lock_wait_private
14 0.0% 0.1% __memchr_avx2
13 0.0% 0.1% operator new(unsigned long)@@GLIBCXX_3.4
7 0.0% 0.0% std::ostream::put(char)@@GLIBCXX_3.4
6 0.0% 0.0% v8::platform::DefaultJobState::NotifyConcurrencyIncrease()
6 0.0% 0.0% __getrusage
6 0.0% 0.0% __GI___printf_fp_l
5 0.0% 0.0% __vfprintf_internal
5 0.0% 0.0% __GI___pthread_mutex_unlock
4 0.0% 0.0% operator new[](unsigned long)@@GLIBCXX_3.4
4 0.0% 0.0% node::binding::GetInternalBinding(v8::FunctionCallbackInfo<v8::Value> const&)
4 0.0% 0.0% fwrite
4 0.0% 0.0% __memset_avx2_unaligned_erms
3 0.0% 0.0% std::ostream::sentry::sentry(std::ostream&)@@GLIBCXX_3.4
3 0.0% 0.0% __memcmp_avx2_movbe
3 0.0% 0.0% __GI___pthread_rwlock_unlock
3 0.0% 0.0% __GI__IO_file_xsputn
2 0.0% 0.0% std::basic_ostream<char, std::char_traits<char> >& std::__ostream_insert<char, std::char_traits<char> >(std::basic_ostream<char, std::char_traits<char> >&, char const*, long)@@GLIBCXX_3.4.9
2 0.0% 0.0% std::__detail::_Prime_rehash_policy::_M_next_bkt(unsigned long) const@@GLIBCXX_3.4.18
2 0.0% 0.0% operator delete(void*)@@GLIBCXX_3.4
2 0.0% 0.0% new_do_write
2 0.0% 0.0% fflush
2 0.0% 0.0% __GI___pthread_rwlock_wrlock
2 0.0% 0.0% __GI___pthread_mutex_init
2 0.0% 0.0% __GI__IO_file_sync
1 0.0% 0.0% v8::platform::DefaultJobState::JobDelegate::GetTaskId()
1 0.0% 0.0% v8::platform::DefaultJobState::CappedMaxConcurrency(unsigned long) const
1 0.0% 0.0% v8::platform::DefaultJobHandle::~DefaultJobHandle()
1 0.0% 0.0% v8::platform::DefaultJobHandle::Join()
1 0.0% 0.0% uv_hrtime
1 0.0% 0.0% std::ostream& std::ostream::_M_insert<unsigned long>(unsigned long)@@GLIBCXX_3.4.9
1 0.0% 0.0% std::ostream& std::ostream::_M_insert<long>(long)@@GLIBCXX_3.4.9
1 0.0% 0.0% std::num_put<char, std::ostreambuf_iterator<char, std::char_traits<char> > >::do_put(std::ostreambuf_iterator<char, std::char_traits<char> >, std::ios_base&, char, unsigned long) const@@GLIBCXX_3.4
1 0.0% 0.0% std::__detail::_Prime_rehash_policy::_M_need_rehash(unsigned long, unsigned long, unsigned long) const@@GLIBCXX_3.4.18
1 0.0% 0.0% std::_Sp_counted_base<(__gnu_cxx::_Lock_policy)2>::_M_release_last_use_cold()
1 0.0% 0.0% std::_Rb_tree_increment(std::_Rb_tree_node_base*)@@GLIBCXX_3.4
1 0.0% 0.0% read_int
1 0.0% 0.0% operator delete(void*, unsigned long)@@CXXABI_1.3.9
1 0.0% 0.0% node::native_module::NativeModuleEnv::ModuleIdsGetter(v8::Local<v8::Name>, v8::PropertyCallbackInfo<v8::Value> const&)
1 0.0% 0.0% node::WorkerThreadsTaskRunner::NumberOfWorkerThreads() const
1 0.0% 0.0% node::NodePlatform::PostJob(v8::TaskPriority, std::unique_ptr<v8::JobTask, std::default_delete<v8::JobTask> >)
1 0.0% 0.0% node::NodePlatform::MonotonicallyIncreasingTime()
1 0.0% 0.0% malloc_consolidate
1 0.0% 0.0% hack_digit
1 0.0% 0.0% alloc_perturb
1 0.0% 0.0% __mpn_divrem
1 0.0% 0.0% __getpid
1 0.0% 0.0% __GI___pthread_sigmask
1 0.0% 0.0% __GI___pthread_rwlock_init
1 0.0% 0.0% __GI___pthread_enable_asynccancel
1 0.0% 0.0% __GI___pthread_disable_asynccancel
1 0.0% 0.0% __GI___madvise
1 0.0% 0.0% __GI___libc_read
1 0.0% 0.0% __GI__IO_setb
1 0.0% 0.0% __GI__IO_default_xsputn
1 0.0% 0.0% _IO_old_init

[Summary]:
ticks total nonlib name
691 0.9% 3.8% JavaScript
17269 23.2% 96.1% C++
42130 56.7% 234.5% GC
56330 75.8% Shared libraries
9 0.0% Unaccounted

[C++ entry points]:
ticks cpp total name
2537 64.7% 3.4% __strlen_avx2
689 17.6% 0.9% __memcpy_avx_unaligned_erms
197 5.0% 0.3% node::contextify::ContextifyContext::CompileFunction(v8::FunctionCallbackInfo<v8::Value> const&)
107 2.7% 0.1% node::native_module::NativeModuleEnv::CompileFunction(v8::FunctionCallbackInfo<v8::Value> const&)
63 1.6% 0.1% _int_malloc
63 1.6% 0.1% __GI___libc_malloc
34 0.9% 0.0% __tls_get_addr
33 0.8% 0.0% _int_free
21 0.5% 0.0% unlink_chunk.constprop.0
21 0.5% 0.0% __GI___libc_write
19 0.5% 0.0% cfree@GLIBC_2.2.5
18 0.5% 0.0% __GI___munmap
15 0.4% 0.0% __GI___pthread_mutex_lock
14 0.4% 0.0% __memchr_avx2
14 0.4% 0.0% __GI___mprotect
10 0.3% 0.0% __GI___mmap
9 0.2% 0.0% __GI___pthread_mutex_unlock_usercnt
9 0.2% 0.0% __GI___lll_lock_wake
7 0.2% 0.0% __GI___lll_lock_wait
4 0.1% 0.0% std::ostream::put(char)@@GLIBCXX_3.4
4 0.1% 0.0% node::binding::GetInternalBinding(v8::FunctionCallbackInfo<v8::Value> const&)
4 0.1% 0.0% fputc
3 0.1% 0.0% operator new[](unsigned long)@@GLIBCXX_3.4
3 0.1% 0.0% __memcmp_avx2_movbe
3 0.1% 0.0% __GI___lll_lock_wake_private
2 0.1% 0.0% operator new(unsigned long)@@GLIBCXX_3.4
2 0.1% 0.0% __memset_avx2_unaligned_erms
2 0.1% 0.0% __GI___pthread_rwlock_unlock
2 0.1% 0.0% __GI__IO_file_xsputn
1 0.0% 0.0% std::ostream::sentry::sentry(std::ostream&)@@GLIBCXX_3.4
1 0.0% 0.0% std::__detail::_Prime_rehash_policy::_M_next_bkt(unsigned long) const@@GLIBCXX_3.4.18
1 0.0% 0.0% std::_Rb_tree_increment(std::_Rb_tree_node_base*)@@GLIBCXX_3.4
1 0.0% 0.0% node::native_module::NativeModuleEnv::ModuleIdsGetter(v8::Local<v8::Name>, v8::PropertyCallbackInfo<v8::Value> const&)
1 0.0% 0.0% fwrite
1 0.0% 0.0% __GI___pthread_rwlock_wrlock
1 0.0% 0.0% __GI___pthread_mutex_unlock
1 0.0% 0.0% __GI___pthread_mutex_init
1 0.0% 0.0% __GI__IO_setb
1 0.0% 0.0% __GI__IO_file_sync

[Bottom up (heavy) profile]:
Note: percentage shows a share of a particular caller in the total
amount of its parent calls.
Callers occupying less than 1.0% are not shown.

ticks parent name
56310 75.8% /usr/lib64/libnode.so.93
26738 47.5% /usr/lib64/libnode.so.93
24277 90.8% LazyCompile: *getFnSignature /home/mary/node_modules/saxon-js/SaxonJS2N.js:4936:508
23850 98.2% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4673:171
23850 100.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4633:36
18765 78.7% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4672:381
5085 21.3% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4672:425
427 1.8% LazyCompile: ~<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4673:171
325 76.1% LazyCompile: ~<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4633:36
325 100.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4672:425
69 16.2% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4633:36
69 100.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4672:425
33 7.7% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4642:362
33 100.0% /usr/lib64/libnode.so.93
1215 4.5% LazyCompile: ~<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:5013:91

11758 15.8% __futex_abstimed_wait_common

2542 3.4% __strlen_avx2
2537 99.8% /usr/lib64/libnode.so.93
2510 98.9% LazyCompile: *getFnSignature /home/mary/node_modules/saxon-js/SaxonJS2N.js:4936:508
2476 98.6% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4673:171
2476 100.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4633:36
1949 78.7% LazyCompile: *<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4672:381
527 21.3% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4672:425
34 1.4% LazyCompile: ~<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4673:171
24 70.6% LazyCompile: ~<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4633:36
24 100.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4672:425
9 26.5% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4633:36
9 100.0% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4672:425
1 2.9% Function: ^<anonymous> /home/mary/node_modules/saxon-js/SaxonJS2N.js:4642:362
1 100.0% /usr/lib64/libnode.so.93

(2-2/2)