Here is some more detailed output from another run, in which clearly
the profiled body is taking longer than the sample interval to execute,
but fewer samples are taken than expected:
Evaluation took:
22.683 seconds of real time
26.197599 seconds of total run time (14.495562 user, 11.702037 system)
[ Run times consist of 5.090 seconds GC time, and 21.108 seconds non-GC time. ]
115.50% CPU
45,252,381,684 processor cycles
65 page faults
1,163,395,360 bytes consed
Number of samples: 2
Sample interval: 0.01 seconds
Total sampling time: 0.02 seconds
Number of cycles: 0
Sampled threads:
#<SB-THREAD:THREAD "repl-thread" RUNNING {1003594E71}>
Here is some more detailed output from another run, in which clearly
the profiled body is taking longer than the sample interval to execute,
but fewer samples are taken than expected:
> (sb-sprof: with-profiling (:max-samples 2000 :report :flat :loop nil)
(time (restore-sheets)))
Evaluation took:
22.683 seconds of real time
26.197599 seconds of total run time (14.495562 user, 11.702037 system)
[ Run times consist of 5.090 seconds GC time, and 21.108 seconds non-GC time. ]
115.50% CPU
45,252,381,684 processor cycles
65 page faults
1,163,395,360 bytes consed
Number of samples: 2
Sample interval: 0.01 seconds
Total sampling time: 0.02 seconds
Number of cycles: 0
Sampled threads:
#<SB-THREAD:THREAD "repl-thread" RUNNING {1003594E71}>
Self Total Cumul ------- ------- ------- ------- ------- ------- ------- ------- ------- -- FUN-[FORM- FUN-[RELEASE- FD-STREAM- RESOURCES] 1153]1161) CALL-WITH- TIMING SIMPLE- EVAL-IN- LEXENV TRACK-PACKAGE CALL-WITH- RETRY-RESTART CALL-WITH- BUFFER- SYNTAX EVAL-FOR- EMACS PROCESS- REQUESTS :CALL-WITH- BREAK-HOOK CALL-WITH- DEBUGGER- HOOK) CALL-WITH- BINDINGS CALL-WITH- CONNECTION HANDLE- REQUESTS INTERRUPTS- BODY-[BLOCK391] 396) :WITH-MUTEX- THUNK) INTERRUPTS- BODY-[CALL- WITH-MUTEX] 293) :CALL-WITH- MUTEX :INITIAL- THREAD- FUNCTION trampoline" UNIX-REALPATH :MAP-DIRECTORY :CALL-WITH- NATIVE- DIRECTORY- ITERATOR :MAP-MATCHING- DIRECTORIES :RELEASE- FD-STREAM- RESOURCES :FAST-METHOD SB-GRAY::PCL-CLOSE (SB-KERNEL: ANSI-STREAM) ) FUN-[EXIT- TAG-[RESTORE] 342]350) ------- ------- ------- ------- ------- ------- ------- ------- ------- -- :CALL-GRAPH 2 samples {100970C811}>
Nr Count % Count % Count % Calls Function
-------
1 1 50.0 1 50.0 1 50.0 - "foreign function getattrlist"
2 1 50.0 1 50.0 2 100.0 - (FLET #:CLEANUP-
3 0 0.0 2 100.0 2 100.0 1 RESTORE-SHEETS
4 0 0.0 2 100.0 2 100.0 - SB-EXT:
5 0 0.0 2 100.0 2 100.0 - "Unknown component: #x1004DFB230"
6 0 0.0 2 100.0 2 100.0 - SB-INT:
7 0 0.0 2 100.0 2 100.0 - SWANK::EVAL-REGION
8 0 0.0 2 100.0 2 100.0 - "Unknown component: #x10054130D0"
9 0 0.0 2 100.0 2 100.0 - SWANK::
10 0 0.0 2 100.0 2 100.0 - SWANK::
11 0 0.0 2 100.0 2 100.0 - SWANK::
12 0 0.0 2 100.0 2 100.0 - SWANK::REPL-EVAL
13 0 0.0 2 100.0 2 100.0 - SWANK::
14 0 0.0 2 100.0 2 100.0 - SWANK::
15 0 0.0 2 100.0 2 100.0 - "Unknown component: #x10044F60E0"
16 0 0.0 2 100.0 2 100.0 - SWANK-BACKEND:
17 0 0.0 2 100.0 2 100.0 - (FLET SWANK-BACKEND:
18 0 0.0 2 100.0 2 100.0 - SWANK::
19 0 0.0 2 100.0 2 100.0 - SWANK::
20 0 0.0 2 100.0 2 100.0 - SWANK::
21 0 0.0 2 100.0 2 100.0 - (FLET #:WITHOUT-
22 0 0.0 2 100.0 2 100.0 - (FLET SB-THREAD:
23 0 0.0 2 100.0 2 100.0 - (FLET #:WITHOUT-
24 0 0.0 2 100.0 2 100.0 - SB-THREAD:
25 0 0.0 2 100.0 2 100.0 - SB-THREAD:
26 0 0.0 2 100.0 2 100.0 - "foreign function call_into_lisp"
27 0 0.0 2 100.0 2 100.0 - "foreign function new_thread_
28 0 0.0 2 100.0 2 100.0 - "foreign function _pthread_start"
29 0 0.0 2 100.0 2 100.0 - "foreign function thread_start"
30 0 0.0 1 50.0 2 100.0 - "foreign function sb_realpath"
31 0 0.0 1 50.0 2 100.0 - SB-UNIX:
32 0 0.0 1 50.0 2 100.0 - SB-IMPL:
33 0 0.0 1 50.0 2 100.0 - (LAMBDA (PATHNAME))
34 0 0.0 1 50.0 2 100.0 - (FLET SB-IMPL::ITERATE)
35 0 0.0 1 50.0 2 100.0 - SB-IMPL:
36 0 0.0 1 50.0 2 100.0 - SB-IMPL:
37 0 0.0 1 50.0 2 100.0 - DIRECTORY
38 0 0.0 1 50.0 2 100.0 - SB-IMPL:
39 0 0.0 1 50.0 2 100.0 - (SB-PCL:
40 0 0.0 1 50.0 2 100.0 - (FLET #:CLEANUP-
41 0 0.0 1 50.0 2 100.0 5298 RESTORE
-------
0 0.0 elsewhere
#<SB-SPROF: