Main INDEX, Monthly INDEX, PREV, NEXT
Make New Entry, Make Followup Entry

User name Reinhold

Log entry time 10:59:16 on September 28, 2009

Entry number 187479

keyword=Where the ENGINE spends its time (sort of obvious)

Below find a detailed listing on how much time the ENGINE spends in each major subprocess. The obvious answer is in the tracking routines. Thew runs are for 40 uA carbon, 10 uA cromium, 4 uA water. Note that which tracking dominates, HKS or HES, seems to vary:

 
     -------------------------------------
Carbon:
-------------------------------------

Processed:
15 / 15 events of type 0
52 / 52 events of type 2
9948 / 9948 events of type 3
1000 / 1000 events of type 4
11000 / 11000 total (neglecting scalers)
for run# 75818
Analysis time = 0 min. 47 sec.
Total CPU time: 46.825882
========================================
g_decode_event_by_bank: 2.134732 ( 4.6%)
g_analyze_pedestal: 0.011998 ( 0.0%)
g_calc_pedestal: 0.000000 ( 0.0%)
g_trans_misc: 0.038995 ( 0.1%)
g_analyze_misc: 0.019988 ( 0.0%)
g_analyze_trig: 0.064994 ( 0.1%)

Time in e_reconstruction: 24.138489 (51.5%)
---------------------------------------
e_raw_dump_all: 0.003002 ( 0.0%)
e_analyze_tul: 0.043004 ( 0.1%)
e_trans_misc: 0.003998 ( 0.0%)
e_trans_scin: 0.982870 ( 2.1%)
e_calc_trigtime: 0.021996 ( 0.0%)
e_track: 21.209873 (45.3%)
e_select_good_tracks: 1.301821 ( 2.8%)
e_targ_trans: 0.305951 ( 0.7%)
e_link_tracks: 0.058987 ( 0.1%)
e_physics: 0.137998 ( 0.3%)

Time in h_reconstruction: 18.537945 (39.6%)
---------------------------------------
h_raw_dump_all: 0.013995 ( 0.0%)
h_analyze_tul: 0.214980 ( 0.5%)
h_trans_misc: 0.005999 ( 0.0%)
h_trans_scin: 1.476706 ( 3.2%)
h_trans_dc: 0.423933 ( 0.9%)
h_track: 12.891880 (27.5%)
h_select_good_tracks: 0.070984 ( 0.2%)
h_targ_trans_rt: 0.128985 ( 0.3%)
h_tof: 0.457916 ( 1.0%)
h_aero: 0.919878 ( 2.0%)
h_water: 0.927886 ( 2.0%)
h_lucite: 0.689887 ( 1.5%)
h_link_tracks: 0.063975 ( 0.1%)
h_physics: 0.149965 ( 0.3%)

Time in c_reconstruction: 0.035998 ( 0.1%)
---------------------------------------

-------------------------------------
Cromium:
-------------------------------------

Processed:
4 / 4 events of type 0
14 / 14 events of type 2
9986 / 9986 events of type 3
1000 / 1000 events of type 4
11000 / 11000 total (neglecting scalers)
for run# 75809
Analysis time = 1 min. 1 sec.
Total CPU time: 61.220692
========================================
g_decode_event_by_bank: 2.986495 ( 4.9%)
g_analyze_pedestal: 0.009996 ( 0.0%)
g_calc_pedestal: 0.001000 ( 0.0%)
g_trans_misc: 0.034008 ( 0.1%)
g_analyze_misc: 0.025000 ( 0.0%)
g_analyze_trig: 0.057999 ( 0.1%)

Time in e_reconstruction: 23.893398 (39.0%)
---------------------------------------
e_raw_dump_all: 0.009000 ( 0.0%)
e_analyze_tul: 0.049994 ( 0.1%)
e_trans_misc: 0.010997 ( 0.0%)
e_trans_scin: 0.959871 ( 1.6%)
e_calc_trigtime: 0.012996 ( 0.0%)
e_track: 21.488777 (35.1%)
e_select_good_tracks: 0.807855 ( 1.3%)
e_targ_trans: 0.271953 ( 0.4%)
e_link_tracks: 0.068985 ( 0.1%)
e_physics: 0.137968 ( 0.2%)

Time in h_reconstruction: 32.432106 (53.0%)
---------------------------------------
h_raw_dump_all: 0.015997 ( 0.0%)
h_analyze_tul: 0.329929 ( 0.5%)
h_trans_misc: 0.012006 ( 0.0%)
h_trans_scin: 2.079716 ( 3.4%)
h_trans_dc: 0.895842 ( 1.5%)
h_track: 25.760096 (42.1%)
h_select_good_tracks: 0.062999 ( 0.1%)
h_targ_trans_rt: 0.104991 ( 0.2%)
h_tof: 0.252942 ( 0.4%)
h_aero: 0.985861 ( 1.6%)
h_water: 0.957830 ( 1.6%)
h_lucite: 0.738915 ( 1.2%)
h_link_tracks: 0.054988 ( 0.1%)
h_physics: 0.102995 ( 0.2%)

Time in c_reconstruction: 0.031994 ( 0.1%)
---------------------------------------
-------------------------------------
Water:
-------------------------------------

Processed:
4 / 4 events of type 0
2409 / 2409 events of type 1
11 / 11 events of type 2
7580 / 7580 events of type 3
1000 / 1000 events of type 4
11000 / 11000 total (neglecting scalers)
for run# 75831
Analysis time = 0 min. 54 sec.
Total CPU time: 54.464718
========================================
g_decode_event_by_bank: 2.887592 ( 5.3%)
g_analyze_pedestal: 0.011999 ( 0.0%)
g_calc_pedestal: 0.001000 ( 0.0%)
g_trans_misc: 0.037991 ( 0.1%)
g_analyze_misc: 0.017005 ( 0.0%)
g_analyze_trig: 0.048000 ( 0.1%)

Time in e_reconstruction: 16.926212 (31.1%)
---------------------------------------
e_raw_dump_all: 0.001999 ( 0.0%)
e_analyze_tul: 0.033993 ( 0.1%)
e_trans_misc: 0.006003 ( 0.0%)
e_trans_scin: 0.697834 ( 1.3%)
e_calc_trigtime: 0.008995 ( 0.0%)
e_track: 15.426498 (28.3%)
e_select_good_tracks: 0.363951 ( 0.7%)
e_targ_trans: 0.219969 ( 0.4%)
e_link_tracks: 0.043997 ( 0.1%)
e_physics: 0.087974 ( 0.2%)

Time in h_reconstruction: 32.738186 (60.1%)
---------------------------------------
h_raw_dump_all: 0.016998 ( 0.0%)
h_analyze_tul: 0.304012 ( 0.6%)
h_trans_misc: 0.005001 ( 0.0%)
h_trans_scin: 2.067722 ( 3.8%)
h_trans_dc: 0.890897 ( 1.6%)
h_track: 26.109114 (47.9%)
h_select_good_tracks: 0.067989 ( 0.1%)
h_targ_trans_rt: 0.113977 ( 0.2%)
h_tof: 0.263961 ( 0.5%)
h_aero: 0.986864 ( 1.8%)
h_water: 0.923841 ( 1.7%)
h_lucite: 0.720843 ( 1.3%)
h_link_tracks: 0.051974 ( 0.1%)
h_physics: 0.107000 ( 0.2%)

Time in c_reconstruction: 0.015992 ( 0.0%)
---------------------------------------