[Chicken-users] profile data: "calls" too high

[Claude Marinier]

Hi,

I am new to profiling. I am surprized by the high count of the first function in the table: make-date. When I grep for "make-date", it confirms that it appears in only one file. I have attached this file. From this, I expect make-date to be called as often as time-stamp and make-time which are called three times.

What am I missing?

Thanks.


procedure                  calls  seconds  average  percent
-----------------------------------------------------------
make-date                    284    6.780    0.023   25.347
process-lines              48174    6.775    0.000   25.332
parse                      48173    6.548    0.000   24.480
update-IPv4-counters       48165    3.944    0.000   14.745
update-IP-record           48165    1.856    0.000    6.938
update-TCP-UDP-counters    48165    0.224    0.000    0.837
sc-minus-one              164866    0.160    0.000    0.598
sc-plus-one               141820    0.144    0.000    0.538
pick-port                  48165    0.076    0.000    0.284
sc-zero                    96292    0.064    0.000    0.239
update-in-counters         30021    0.060    0.000    0.224
get-TCP-counters           47952    0.040    0.000    0.149
set-TCP-counters           47933    0.028    0.000    0.104
update-out-counters        18144    0.024    0.000    0.089
process-options                2    0.008    0.004    0.029
dump-in-out-counters         116    0.004    0.000    0.014
dump-TCP-UDP-ports            38    0.004    0.000    0.014
dump-IP-protocols             19    0.004    0.000    0.014
dump-data                      1    0.004    0.004    0.014
write-with-indent            264    0.000    0.000    0.000
get-UDP-counters             251    0.000    0.000    0.000
set-UDP-counters             232    0.000    0.000    0.000
num-out-bytes                211    0.000    0.000    0.000
num-in-packets               137    0.000    0.000    0.000
num-in-bytes                 116    0.000    0.000    0.000
num-out-packets               21    0.000    0.000    0.000
make-IP-record                19    0.000    0.000    0.000
get-ICMP-counters             19    0.000    0.000    0.000
get-ICMP6-counters            19    0.000    0.000    0.000
get-route-counters            19    0.000    0.000    0.000
get-other-counters            19    0.000    0.000    0.000
get-wrong-counters            19    0.000    0.000    0.000
add-bytes                      8    0.000    0.000    0.000
inc-packets                    8    0.000    0.000    0.000
time-stamp                     3    0.000    0.000    0.000
make-time                      3    0.000    0.000    0.000
num-packets                    3    0.000    0.000    0.000
init-port-table                2    0.000    0.000    0.000
log-this                       2    0.000    0.000    0.000
dump-counters                  2    0.000    0.000    0.000
num-bytes                      2    0.000    0.000    0.000
address-OK?                    2    0.000    0.000    0.000
check-IP-address               2    0.000    0.000    0.000
main                           1    0.000    0.000    0.000
setup-term-handler             1    0.000    0.000    0.000
IPv4-dec->hex                  1    0.000    0.000    0.000
make-date-long                 1    0.000    0.000    0.000
time-stamp-long                1    0.000    0.000    0.000
make-dump-file-name            1    0.000    0.000    0.000
make-time-long                 1    0.000    0.000    0.000
process-header-line            1    0.000    0.000    0.000
term-handler                   0    0.000    0.000    0.000
alarm-handler                  0    0.000    0.000    0.000
setup-alarm-handler            0    0.000    0.000    0.000
pad-right                      0    0.000    0.000    0.000
pad-left                       0    0.000    0.000    0.000
bad-header                     0    0.000    0.000    0.000
bad-option-missing-value       0    0.000    0.000    0.000
set-ICMP-counters              0    0.000    0.000    0.000
set-ICMP6-counters             0    0.000    0.000    0.000
set-route-counters             0    0.000    0.000    0.000
set-other-counters             0    0.000    0.000    0.000
set-wrong-counters             0    0.000    0.000    0.000
update-IPv6-counters           0    0.000    0.000    0.000
set-repeating-alarm!           0    0.000    0.000    0.000
no-file                        0    0.000    0.000    0.000
init-IP-protocol-table         0    0.000    0.000    0.000
tokens->string                 0    0.000    0.000    0.000
bad-address                    0    0.000    0.000    0.000
usage                          0    0.000    0.000    0.000
bad-option-1-letter            0    0.000    0.000    0.000
missing-routing-prefix         0    0.000    0.000    0.000
bad-routing-prefix             0    0.000    0.000    0.000
bad-option-unknown             0    0.000    0.000    0.000

time-stamp.scm
Description: Binary data