New patch for RootTree.cc from Brian:

--- src/IOPool/Input/src/RootTree.cc    20 Aug 2009 15:31:44 -0000      1.50
+++ src/IOPool/Input/src/RootTree.cc    7 Jan 2010 04:29:15 -0000
@@ -121,6 +121,12 @@
 
   void
   RootTree::setCacheSize(unsigned int cacheSize) {
+    // FIXME: backward way of setting this to be fixed in ROOT 5.25.
+    // Learn entries must be set before tree_->SetCacheSize() is called.
+    // However, the internal fgLearnEntries variable is protected.
+    // So, we must set it through this silly temporary object.
+    TTreeCache tmpTreeCache;
+    tmpTreeCache.SetLearnEntries(2);
     tree_->SetCacheSize(static_cast<Long64_t>(cacheSize));
     treeCache_ = dynamic_cast<TTreeCache *>(filePtr_->GetCacheRead());
     filePtr_->SetCacheRead(0);

Tests performed:

CSCS

  CSCS-PAT1 CMSSW_3_3_2 20100104 CSCS-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2 20100108 CSCS-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2_patched3 20100107 CSCS-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2_rootTreePatch 20100107
Success 100.0% (20 / 20) 100.0% (20 / 20) 100.0% (20 / 20) 100.0% (20 / 20)
WrapperTime 4748.45 +- 269.03 2467.10 +- 30.58 3377.35 +- 393.29 3495.05 +- 424.69
ExeTime 4734.60 +- 268.75 2457.05 +- 30.59 3365.45 +- 393.44 3485.05 +- 424.54
MinEventTime 0.07 +- 0.01 0.04 +- 0.00 0.05 +- 0.00 0.05 +- 0.00
UserTime 2775.18 +- 137.25 2186.42 +- 18.67 2428.11 +- 72.62 2372.48 +- 104.44
CpuPercentage 59.40 +- 1.93 90.00 +- 0.89 74.05 +- 6.69 69.60 +- 5.47
User_ReadkBEvt 89.96 +- 0.26 89.96 +- 0.26 89.96 +- 0.26 89.96 +- 0.26
AvgEventTime 0.47 +- 0.03 0.24 +- 0.00 0.33 +- 0.04 0.34 +- 0.04
MaxEventTime 29.35 +- 4.97 27.13 +- 5.72 28.86 +- 4.48 28.67 +- 4.97
TotalJobTime 4670.27 +- 268.66 2415.22 +- 31.45 3314.64 +- 393.76 3440.91 +- 423.73
StageoutTime -1.00 +- 0.00 -1.00 +- 0.00 -1.00 +- 0.00 -1.00 +- 0.00
SysTime 62.79 +- 7.68 37.25 +- 3.55 53.15 +- 9.87 45.24 +- 10.31
READ
tstoragefile-read-actual-total-megabytes 878.52 +- 2.59 49.76 +- 1.61 1257.11 +- 113.56 365.69 +- 148.17
tstoragefile-read-async-total-megabytes // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-total-megabytes 878.52 +- 2.59 878.52 +- 2.59 878.52 +- 2.59 878.52 +- 2.59
dcap-read-total-megabytes 878.52 +- 2.59 49.76 +- 1.61 1257.11 +- 113.56 365.69 +- 148.17
file-read-total-megabytes 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-via-cache-total-megabytes // 828.76 +- 2.60 512.83 +- 148.39 512.83 +- 148.39
tstoragefile-read-actual-total-msecs 1886837.50 +- 170578.68 18615.19 +- 923.84 872015.90 +- 317991.83 763446.28 +- 364668.74
tstoragefile-read-async-total-msecs // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-total-msecs 1887085.50 +- 170593.32 241545.55 +- 22323.80 874652.20 +- 317602.93 1070623.25 +- 317474.08
dcap-read-total-msecs 1886553.00 +- 170565.43 18594.75 +- 923.61 871848.95 +- 317942.76 763311.89 +- 364617.35
file-read-total-msecs 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-via-cache-total-msecs // 1662.01 +- 33.99 73988.02 +- 30843.13 229912.24 +- 78746.00
tstoragefile-read-actual-num-operations 134683.15 +- 311.18 10308.65 +- 7.33 63615.00 +- 20884.54 59502.30 +- 21988.79
tstoragefile-read-actual-num-successful-operations 134683.15 +- 311.18 10308.65 +- 7.33 63615.00 +- 20884.54 59502.30 +- 21988.79
tstoragefile-read-async-num-operations // 2.50 +- 0.50 2.50 +- 0.50 2.50 +- 0.50
tstoragefile-read-async-num-successful-operations // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-num-operations 134683.15 +- 311.18 134683.15 +- 311.18 134683.15 +- 311.18 134683.15 +- 311.18
dcap-read-num-operations 134683.15 +- 311.18 10308.65 +- 7.33 63615.00 +- 20884.54 59502.30 +- 21988.79
file-read-num-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-num-successful-operations 134683.15 +- 311.18 134683.15 +- 311.18 134683.15 +- 311.18 134683.15 +- 311.18
dcap-read-num-successful-operations 134683.15 +- 311.18 10308.65 +- 7.33 63615.00 +- 20884.54 59502.30 +- 21988.79
file-read-num-successful-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-via-cache-num-operations // 124485.90 +- 314.45 124485.90 +- 314.45 124485.90 +- 314.45
tstoragefile-read-via-cache-num-successful-operations // 124374.50 +- 314.36 75180.85 +- 21833.96 75180.85 +- 21833.96
READV
tstoragefile-readv-actual-total-megabytes // 2303.24 +- 20.72 // 551.14 +- 157.24
dcap-readv-total-megabytes 0.00 +- 0.00 2303.24 +- 20.72 0.00 +- 0.00 551.14 +- 157.24
file-readv-total-megabytes 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-readv-actual-total-msecs // 224297.75 +- 21278.53 // 305487.35 +- 75294.61
dcap-readv-total-msecs 0.00 +- 0.00 224296.85 +- 21278.62 0.00 +- 0.00 305487.05 +- 75294.56
file-readv-total-msecs 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-readv-actual-num-operations // 111.40 +- 0.86 // 28.65 +- 7.93
tstoragefile-readv-actual-num-successful-operations // 111.40 +- 0.86 // 28.65 +- 7.93
dcap-readv-num-operations 0.00 +- 0.00 111.40 +- 0.86 0.00 +- 0.00 28.65 +- 7.93
file-readv-num-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
dcap-readv-num-successful-operations 0.00 +- 0.00 111.40 +- 0.86 0.00 +- 0.00 28.65 +- 7.93
file-readv-num-successful-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00

  • CSCS-PAT1-QCD_Pt80Summer09-Overview.jpg:
    CSCS-PAT1-QCD_Pt80Summer09-Overview.jpg

  • CSCS-PAT1-QCD_Pt80Summer09-TimeEvent_cpuPercentage.jpg:
    CSCS-PAT1-QCD_Pt80Summer09-TimeEvent_cpuPercentage.jpg

  • CSCS-PAT1-QCD_Pt80Summer09-TimeEvent_eleIsoDepositTk-CandIsoDepositProducer_secs.jpg:
    CSCS-PAT1-QCD_Pt80Summer09-TimeEvent_eleIsoDepositTk-CandIsoDepositProducer_secs.jpg

KNU

  KNU-PAT1 CMSSW_3_3_2 20100104 KNU-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2 20100108 KNU-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2_patched3 20100107 KNU-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2_rootTreePatch 20100107
Success 100.0% (7 / 7) 100.0% (18 / 18) 100.0% (18 / 18) 100.0% (18 / 18)
WrapperTime 2987.57 +- 194.65 2519.94 +- 331.85 3050.56 +- 766.57 3099.78 +- 503.63
ExeTime 2977.43 +- 194.83 2509.67 +- 329.85 3039.56 +- 765.44 3087.56 +- 502.41
MinEventTime 0.06 +- 0.01 0.05 +- 0.01 0.05 +- 0.01 0.06 +- 0.01
UserTime 2492.51 +- 60.46 2337.22 +- 259.17 2417.91 +- 327.36 2719.57 +- 390.41
CpuPercentage 85.86 +- 4.64 94.78 +- 2.76 83.06 +- 9.82 90.28 +- 7.96
User_ReadkBEvt 89.86 +- 0.21 89.95 +- 0.28 89.95 +- 0.28 89.95 +- 0.28
AvgEventTime 0.29 +- 0.02 0.24 +- 0.03 0.30 +- 0.08 0.30 +- 0.05
MaxEventTime 32.00 +- 5.38 30.88 +- 9.54 33.04 +- 6.58 37.87 +- 10.78
TotalJobTime 2907.32 +- 178.34 2448.73 +- 305.33 2963.00 +- 775.45 3020.12 +- 498.80
StageoutTime -1.00 +- 0.00 -1.00 +- 0.00 -1.00 +- 0.00 -1.00 +- 0.00
SysTime 69.28 +- 6.40 48.01 +- 9.28 64.65 +- 10.39 61.25 +- 6.85
READ
tstoragefile-read-actual-total-megabytes 877.49 +- 2.08 49.77 +- 1.61 1257.52 +- 115.26 365.70 +- 148.86
tstoragefile-read-async-total-megabytes // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-total-megabytes 877.49 +- 2.08 878.46 +- 2.71 878.46 +- 2.71 878.46 +- 2.71
dcap-read-total-megabytes 877.49 +- 2.08 49.77 +- 1.61 1257.52 +- 115.26 365.70 +- 148.86
file-read-total-megabytes 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-via-cache-total-megabytes // 828.69 +- 2.61 512.76 +- 149.20 512.76 +- 149.20
tstoragefile-read-actual-total-msecs 405885.86 +- 178639.08 18091.31 +- 16507.88 514148.28 +- 599271.50 227046.48 +- 252397.80
tstoragefile-read-async-total-msecs // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-total-msecs 406489.29 +- 178644.63 104278.78 +- 77166.90 517160.83 +- 599344.75 290868.60 +- 305694.20
dcap-read-total-msecs 405258.43 +- 178631.78 18058.23 +- 16508.13 513912.72 +- 599202.09 226809.22 +- 252378.94
file-read-total-msecs 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-via-cache-total-msecs // 2217.80 +- 999.39 77406.29 +- 28855.22 35940.03 +- 31995.86
tstoragefile-read-actual-num-operations 134566.71 +- 257.77 10308.67 +- 7.31 63524.11 +- 21016.00 59409.17 +- 22122.85
tstoragefile-read-actual-num-successful-operations 134566.71 +- 257.77 10308.67 +- 7.31 63524.11 +- 21016.00 59409.17 +- 22122.85
tstoragefile-read-async-num-operations // 2.50 +- 0.50 2.50 +- 0.50 2.50 +- 0.50
tstoragefile-read-async-num-successful-operations // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-num-operations 134566.71 +- 257.77 134668.78 +- 316.80 134668.78 +- 316.80 134668.78 +- 316.80
dcap-read-num-operations 134566.71 +- 257.77 10308.67 +- 7.31 63524.11 +- 21016.00 59409.17 +- 22122.85
file-read-num-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-num-successful-operations 134566.71 +- 257.77 134668.78 +- 316.80 134668.78 +- 316.80 134668.78 +- 316.80
dcap-read-num-successful-operations 134566.71 +- 257.77 10308.67 +- 7.31 63524.11 +- 21016.00 59409.17 +- 22122.85
file-read-num-successful-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-via-cache-num-operations // 124471.56 +- 319.82 124471.56 +- 319.82 124471.56 +- 319.82
tstoragefile-read-via-cache-num-successful-operations // 124360.11 +- 319.70 75259.61 +- 21974.83 75259.61 +- 21974.83
READV
tstoragefile-readv-actual-total-megabytes // 2304.69 +- 21.24 // 551.28 +- 158.17
dcap-readv-total-megabytes 0.00 +- 0.00 2304.69 +- 21.24 0.00 +- 0.00 551.28 +- 158.17
file-readv-total-megabytes 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-readv-actual-total-msecs // 84795.18 +- 62519.79 // 60917.00 +- 88513.10
dcap-readv-total-msecs 0.00 +- 0.00 84794.50 +- 62519.83 0.00 +- 0.00 60916.77 +- 88513.12
file-readv-total-msecs 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-readv-actual-num-operations // 111.44 +- 0.90 // 28.67 +- 7.97
tstoragefile-readv-actual-num-successful-operations // 111.44 +- 0.90 // 28.67 +- 7.97
dcap-readv-num-operations 0.00 +- 0.00 111.44 +- 0.90 0.00 +- 0.00 28.67 +- 7.97
file-readv-num-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
dcap-readv-num-successful-operations 0.00 +- 0.00 111.44 +- 0.90 0.00 +- 0.00 28.67 +- 7.97
file-readv-num-successful-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00

  • KNU-PAT1-QCD_Pt80Summer09-Overview.jpg:
    KNU-PAT1-QCD_Pt80Summer09-Overview.jpg

  • KNU-PAT1-QCD_Pt80Summer09-TimeEvent_cpuPercentage.jpg:
    KNU-PAT1-QCD_Pt80Summer09-TimeEvent_cpuPercentage.jpg

  • KNU-PAT1-QCD_Pt80Summer09-TimeEvent_eleIsoDepositTk-CandIsoDepositProducer_secs.jpg:
    KNU-PAT1-QCD_Pt80Summer09-TimeEvent_eleIsoDepositTk-CandIsoDepositProducer_secs.jpg

Pisa

  Pisa-PAT1 CMSSW_3_3_2 20100104 Pisa-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2 20100108 Pisa-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2_patched3 20100107 Pisa-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2_rootTreePatch 20100107
Success 100.0% (20 / 20) 80.0% (16 / 20) 85.0% (17 / 20) 84.2% (16 / 19)
Error 50115 // 100.0% 100.0% 100.0%
WrapperTime 5080.45 +- 164.43 2557.12 +- 172.88 3168.94 +- 293.33 2956.62 +- 355.93
ExeTime 5067.00 +- 164.02 2541.62 +- 172.60 3153.59 +- 293.95 2938.62 +- 355.45
MinEventTime 0.10 +- 0.02 0.05 +- 0.00 0.05 +- 0.01 0.05 +- 0.01
UserTime 2655.57 +- 44.44 2300.05 +- 301.43 2490.59 +- 127.94 2260.79 +- 205.31
CpuPercentage 53.65 +- 2.03 91.44 +- 7.00 80.94 +- 10.12 78.88 +- 10.21
User_ReadkBEvt 89.96 +- 0.26 71.98 +- 35.99 76.45 +- 32.12 75.74 +- 32.80
AvgEventTime 0.50 +- 0.02 0.25 +- 0.02 0.31 +- 0.03 0.29 +- 0.04
MaxEventTime 34.55 +- 6.10 29.18 +- 6.00 30.77 +- 5.40 32.72 +- 6.65
TotalJobTime 5002.20 +- 164.07 2487.73 +- 170.37 3098.83 +- 295.48 2879.84 +- 353.79
StageoutTime -1.00 +- 0.00 -1.00 +- 0.00 -1.00 +- 0.00 -1.00 +- 0.00
SysTime 85.46 +- 7.08 44.44 +- 9.27 48.77 +- 2.08 45.25 +- 7.73
READ
tstoragefile-read-actual-total-megabytes 878.52 +- 2.59 50.18 +- 1.54 1265.20 +- 112.12 362.67 +- 155.54
tstoragefile-read-async-total-megabytes // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-total-megabytes 878.52 +- 2.59 878.64 +- 2.86 878.29 +- 2.72 878.39 +- 2.43
dcap-read-total-megabytes 878.52 +- 2.59 50.18 +- 1.54 1265.20 +- 112.12 362.67 +- 155.54
file-read-total-megabytes 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-via-cache-total-megabytes // 828.46 +- 2.81 520.66 +- 145.05 515.72 +- 155.71
tstoragefile-read-actual-total-msecs 2229287.50 +- 162633.93 40657.73 +- 35772.71 536740.51 +- 305627.23 437225.80 +- 294551.16
tstoragefile-read-async-total-msecs // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-total-msecs 2229712.00 +- 162634.12 177276.51 +- 152145.51 540587.45 +- 305246.57 605196.25 +- 360039.49
dcap-read-total-msecs 2228768.00 +- 162642.11 40617.71 +- 35784.47 536499.44 +- 305604.81 437005.82 +- 294507.80
file-read-total-msecs 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-via-cache-total-msecs // 2782.58 +- 805.00 27293.24 +- 25659.02 131947.26 +- 136917.05
tstoragefile-read-actual-num-operations 134683.15 +- 311.18 10310.50 +- 7.04 62668.24 +- 20515.21 58912.94 +- 22980.90
tstoragefile-read-actual-num-successful-operations 134683.15 +- 311.18 10310.50 +- 7.04 62668.24 +- 20515.21 58912.94 +- 22980.90
tstoragefile-read-async-num-operations // 2.62 +- 0.48 2.53 +- 0.50 2.50 +- 0.50
tstoragefile-read-async-num-successful-operations // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-num-operations 134683.15 +- 311.18 134659.56 +- 337.14 134642.88 +- 292.44 134675.00 +- 278.97
dcap-read-num-operations 134683.15 +- 311.18 10310.50 +- 7.04 62668.24 +- 20515.21 58912.94 +- 22980.90
file-read-num-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-num-successful-operations 134683.15 +- 311.18 134659.56 +- 337.14 134642.88 +- 292.44 134675.00 +- 278.97
dcap-read-num-successful-operations 134683.15 +- 311.18 10310.50 +- 7.04 62668.24 +- 20515.21 58912.94 +- 22980.90
file-read-num-successful-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-via-cache-num-operations // 124460.38 +- 340.26 124445.29 +- 295.27 124477.75 +- 282.42
tstoragefile-read-via-cache-num-successful-operations // 124349.06 +- 340.28 76158.18 +- 21442.31 75762.06 +- 22852.94
READV
tstoragefile-readv-actual-total-megabytes // 2301.22 +- 18.94 // 554.11 +- 164.62
dcap-readv-total-megabytes 0.00 +- 0.00 2301.22 +- 18.94 0.00 +- 0.00 554.11 +- 164.62
file-readv-total-megabytes 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-readv-actual-total-msecs // 135572.09 +- 121178.57 // 165105.43 +- 140074.51
dcap-readv-total-msecs 0.00 +- 0.00 135571.36 +- 121178.64 0.00 +- 0.00 165105.34 +- 140074.57
file-readv-total-msecs 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-readv-actual-num-operations // 111.31 +- 0.77 // 28.81 +- 8.29
tstoragefile-readv-actual-num-successful-operations // 111.31 +- 0.77 // 28.81 +- 8.29
dcap-readv-num-operations 0.00 +- 0.00 111.31 +- 0.77 0.00 +- 0.00 28.81 +- 8.29
file-readv-num-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
dcap-readv-num-successful-operations 0.00 +- 0.00 111.31 +- 0.77 0.00 +- 0.00 28.81 +- 8.29
file-readv-num-successful-operations 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00

  • Pisa-PAT1-QCD_Pt80Summer09-Overview.jpg:
    Pisa-PAT1-QCD_Pt80Summer09-Overview.jpg

  • Pisa-PAT1-QCD_Pt80Summer09-TimeEvent_cpuPercentage.jpg:
    Pisa-PAT1-QCD_Pt80Summer09-TimeEvent_cpuPercentage.jpg

  • Pisa-PAT1-QCD_Pt80Summer09-TimeEvent_eleIsoDepositTk-CandIsoDepositProducer_secs.jpg:
    Pisa-PAT1-QCD_Pt80Summer09-TimeEvent_eleIsoDepositTk-CandIsoDepositProducer_secs.jpg

Nebraska

  Nebraska-PAT1 CMSSW_3_3_2 20100104 Nebraska-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2 20100108 Nebraska-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2_patched3 20100107 Nebraska-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2_rootTreePatch 20100107
Success 100.0% (20 / 20) 100.0% (20 / 20) 100.0% (20 / 20) 100.0% (20 / 20)
WrapperTime 3072.75 +- 275.89 2867.95 +- 294.78 2688.55 +- 178.39 4121.15 +- 487.18
ExeTime 3059.40 +- 274.36 2850.30 +- 298.12 2676.35 +- 177.30 4095.05 +- 485.90
UserTime 2524.92 +- 202.47 2453.92 +- 196.99 2393.26 +- 168.04 2422.34 +- 187.29
CpuPercentage 83.30 +- 2.78 87.50 +- 7.48 90.05 +- 2.80 60.55 +- 8.89
User_ReadkBEvt 89.96 +- 0.26 89.96 +- 0.26 89.96 +- 0.26 89.96 +- 0.26
AvgEventTime 0.30 +- 0.03 0.28 +- 0.03 0.26 +- 0.02 0.40 +- 0.05
MaxEventTime 30.63 +- 4.70 34.18 +- 10.48 32.74 +- 1.95 64.32 +- 39.88
TotalJobTime 3007.67 +- 271.72 2789.91 +- 288.02 2592.52 +- 176.09 4039.90 +- 481.49
MinEventTime 0.07 +- 0.01 0.05 +- 0.00 0.05 +- 0.01 0.05 +- 0.00
StageoutTime -1.00 +- 0.00 -1.00 +- 0.00 -1.00 +- 0.00 -1.00 +- 0.00
SysTime 35.50 +- 10.54 38.70 +- 7.93 28.79 +- 9.61 46.52 +- 20.11
READ
tstoragefile-read-actual-total-megabytes 878.52 +- 2.59 49.76 +- 1.61 1257.11 +- 113.56 365.69 +- 148.17
tstoragefile-read-async-total-megabytes // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-total-megabytes 878.52 +- 2.59 878.52 +- 2.59 878.52 +- 2.59 878.52 +- 2.59
file-read-total-megabytes 878.52 +- 2.59 49.76 +- 1.61 1257.11 +- 113.56 365.69 +- 148.17
tstoragefile-read-via-cache-total-megabytes // 828.76 +- 2.60 512.83 +- 148.39 512.83 +- 148.39
tstoragefile-read-actual-total-msecs 478750.00 +- 114271.60 12412.33 +- 6401.97 192313.36 +- 67628.05 1226760.98 +- 551305.79
tstoragefile-read-async-total-msecs // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-total-msecs 479160.80 +- 114312.49 291516.35 +- 247376.46 195465.14 +- 67014.72 1479557.25 +- 464068.44
file-read-total-msecs 478249.75 +- 114223.70 12381.02 +- 6404.85 192120.86 +- 67592.76 1226541.70 +- 551224.48
tstoragefile-read-via-cache-total-msecs // 2469.50 +- 982.68 29918.35 +- 11752.73 190432.20 +- 194706.16
tstoragefile-read-actual-num-operations 134683.15 +- 311.18 10308.65 +- 7.33 63615.00 +- 20884.54 59502.30 +- 21988.79
tstoragefile-read-actual-num-successful-operations 134683.15 +- 311.18 10308.65 +- 7.33 63615.00 +- 20884.54 59502.30 +- 21988.79
tstoragefile-read-async-num-operations // 2.50 +- 0.50 2.50 +- 0.50 2.50 +- 0.50
tstoragefile-read-async-num-successful-operations // 0.00 +- 0.00 0.00 +- 0.00 0.00 +- 0.00
tstoragefile-read-num-operations 134683.15 +- 311.18 134683.15 +- 311.18 134683.15 +- 311.18 134683.15 +- 311.18
file-read-num-operations 134683.15 +- 311.18 10308.65 +- 7.33 63615.00 +- 20884.54 59502.30 +- 21988.79
tstoragefile-read-num-successful-operations 134683.15 +- 311.18 134683.15 +- 311.18 134683.15 +- 311.18 134683.15 +- 311.18
file-read-num-successful-operations 134683.15 +- 311.18 10308.65 +- 7.33 63615.00 +- 20884.54 59502.30 +- 21988.79
tstoragefile-read-via-cache-num-operations // 124485.90 +- 314.45 124485.90 +- 314.45 124485.90 +- 314.45
tstoragefile-read-via-cache-num-successful-operations // 124374.50 +- 314.36 75180.85 +- 21833.96 75180.85 +- 21833.96
READV
tstoragefile-readv-actual-total-megabytes // 2303.24 +- 20.72 // 551.14 +- 157.24
file-readv-total-megabytes 0.00 +- 0.00 2303.24 +- 20.72 0.00 +- 0.00 551.14 +- 157.24
tstoragefile-readv-actual-total-msecs // 281730.40 +- 244216.74 // 250439.55 +- 187004.31
file-readv-total-msecs 0.00 +- 0.00 281729.75 +- 244216.76 0.00 +- 0.00 250439.30 +- 187004.40
tstoragefile-readv-actual-num-operations // 111.40 +- 0.86 // 28.65 +- 7.93
tstoragefile-readv-actual-num-successful-operations // 111.40 +- 0.86 // 28.65 +- 7.93
file-readv-num-operations 0.00 +- 0.00 111.40 +- 0.86 0.00 +- 0.00 28.65 +- 7.93
file-readv-num-successful-operations 0.00 +- 0.00 111.40 +- 0.86 0.00 +- 0.00 28.65 +- 7.93

  • Nebraska-PAT1-QCD_Pt80Summer09-Overview.jpg:
    Nebraska-PAT1-QCD_Pt80Summer09-Overview.jpg

  • Nebraska-PAT1-QCD_Pt80Summer09-TimeEvent_eleIsoDepositTk-CandIsoDepositProducer_secs.jpg:
    Nebraska-PAT1-QCD_Pt80Summer09-TimeEvent_eleIsoDepositTk-CandIsoDepositProducer_secs.jpg

Nebraska value for ttreepatch only sounds strange, test repeated:

  Nebraska-PAT1_RHAUTO_CHAPP_CACHE20 CMSSW_3_3_2_rootTreePatch 20100109
Success 100.0% (20 / 20)
WrapperTime 2773.65 +- 241.05
ExeTime 2760.65 +- 240.27
UserTime 2470.26 +- 235.93
CpuPercentage 90.10 +- 2.45
User_ReadkBEvt 89.96 +- 0.26
AvgEventTime 0.27 +- 0.02
MaxEventTime 30.28 +- 5.53
TotalJobTime 2711.66 +- 240.81
MinEventTime 0.05 +- 0.00
StageoutTime -1.00 +- 0.00
SysTime 33.36 +- 10.01
READ
tstoragefile-read-actual-total-megabytes 365.69 +- 148.17
tstoragefile-read-async-total-megabytes 0.00 +- 0.00
tstoragefile-read-total-megabytes 878.52 +- 2.59
file-read-total-megabytes 365.69 +- 148.17
tstoragefile-read-via-cache-total-megabytes 512.83 +- 148.39
tstoragefile-read-actual-total-msecs 167948.04 +- 80316.19
tstoragefile-read-async-total-msecs 0.00 +- 0.00
tstoragefile-read-total-msecs 219815.30 +- 68130.07
file-read-total-msecs 167772.79 +- 80252.77
tstoragefile-read-via-cache-total-msecs 37995.43 +- 15522.05
tstoragefile-read-actual-num-operations 59502.30 +- 21988.79
tstoragefile-read-actual-num-successful-operations 59502.30 +- 21988.79
tstoragefile-read-async-num-operations 2.50 +- 0.50
tstoragefile-read-async-num-successful-operations 0.00 +- 0.00
tstoragefile-read-num-operations 134683.15 +- 311.18
file-read-num-operations 59502.30 +- 21988.79
tstoragefile-read-num-successful-operations 134683.15 +- 311.18
file-read-num-successful-operations 59502.30 +- 21988.79
tstoragefile-read-via-cache-num-operations 124485.90 +- 314.45
tstoragefile-read-via-cache-num-successful-operations 75180.85 +- 21833.96
READV
tstoragefile-readv-actual-total-megabytes 551.14 +- 157.24
file-readv-total-megabytes 551.14 +- 157.24
tstoragefile-readv-actual-total-msecs 49453.27 +- 17405.46
file-readv-total-msecs 49453.04 +- 17405.40
tstoragefile-readv-actual-num-operations 28.65 +- 7.93
tstoragefile-readv-actual-num-successful-operations 28.65 +- 7.93
file-readv-num-operations 28.65 +- 7.93
file-readv-num-successful-operations 28.65 +- 7.93

-- LeonardoSala - 07-Jan-2010

Edit | Attach | Watch | Print version | History: r4 < r3 < r2 < r1 | Backlinks | Raw View | WYSIWYG | More topic actions
Topic revision: r4 - 2010-01-11 - unknown
 
    • Cern Search Icon Cern Search
    • TWiki Search Icon TWiki Search
    • Google Search Icon Google Search

    Main All webs login

This site is powered by the TWiki collaboration platform Powered by PerlCopyright &© 2008-2024 by the contributing authors. All material on this collaboration platform is the property of the contributing authors.
or Ideas, requests, problems regarding TWiki? use Discourse or Send feedback