Warning, /Validation/EcalTriggerPrimitives/test/validateTPG.csh.oref is written in an unsupported language. File is not indexed.
0001
0002 [oval run] =================================================
0003 [oval run] USER : ebecheva
0004 [oval run] HOST : lxcmsf3.cern.ch
0005 [oval run] run tool: oval
0006 [oval run] final instruction: ./validateTPG_suppressed.csh
0007 [oval run] =================================================
0008
0009 ===================> Step1: executing EDProducer (TPGSimulation)
0010 05-Feb-2009 13:56:17 CET Initiating request to open file rfio:/castor/cern.ch/cms/store/relval/CMSSW_3_0_0_pre6/RelValSingleElectronPt35/GEN-SIM-DIGI-RAW-HLTDEBUG/IDEAL_30X_v1/0005/28116A15-E9DD-DD11-9BA6-001617E30F4C.root
0011 05-Feb-2009 13:56:44 CET Successfully opened file rfio:/castor/cern.ch/cms/store/relval/CMSSW_3_0_0_pre6/RelValSingleElectronPt35/GEN-SIM-DIGI-RAW-HLTDEBUG/IDEAL_30X_v1/0005/28116A15-E9DD-DD11-9BA6-001617E30F4C.root
0012 TimeReport> Report activated
0013 TimeReport> Report columns headings for events: eventnum runnum timetaken
0014 TimeReport> Report columns headings for modules: eventnum runnum modulelabel modulename timetaken
0015 Begin processing the 1st record. Run 1, Event 1001, LumiSection 666670 at 05-Feb-2009 14:01:39 CET
0016 TimeModule> 1001 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.125662
0017 TimeModule> 1001 1 TriggerResults TriggerResultInserter 0.000133991
0018 TimeModule> 1001 1 out PoolOutputModule 0.301427
0019 TimeEvent> 1001 1 0.429005
0020 Begin processing the 2nd record. Run 1, Event 1002, LumiSection 666670 at 05-Feb-2009 14:01:39 CET
0021 TimeModule> 1002 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0565331
0022 TimeModule> 1002 1 TriggerResults TriggerResultInserter 8.2016e-05
0023 TimeModule> 1002 1 out PoolOutputModule 0.281441
0024 TimeEvent> 1002 1 0.33943
0025 Begin processing the 3rd record. Run 1, Event 1003, LumiSection 666670 at 05-Feb-2009 14:01:40 CET
0026 TimeModule> 1003 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.33934
0027 TimeModule> 1003 1 TriggerResults TriggerResultInserter 8.39233e-05
0028 TimeModule> 1003 1 out PoolOutputModule 0.751058
0029 TimeEvent> 1003 1 1.09188
0030 Begin processing the 4th record. Run 1, Event 1004, LumiSection 666670 at 05-Feb-2009 14:01:41 CET
0031 TimeModule> 1004 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0879679
0032 TimeModule> 1004 1 TriggerResults TriggerResultInserter 9.41753e-05
0033 TimeModule> 1004 1 out PoolOutputModule 0.301441
0034 TimeEvent> 1004 1 0.390925
0035 Begin processing the 5th record. Run 1, Event 1005, LumiSection 666670 at 05-Feb-2009 14:01:41 CET
0036 TimeModule> 1005 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.019794
0037 TimeModule> 1005 1 TriggerResults TriggerResultInserter 8.58307e-05
0038 TimeModule> 1005 1 out PoolOutputModule 0.26558
0039 TimeEvent> 1005 1 0.286845
0040 Begin processing the 6th record. Run 1, Event 1006, LumiSection 666670 at 05-Feb-2009 14:01:41 CET
0041 TimeModule> 1006 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0191839
0042 TimeModule> 1006 1 TriggerResults TriggerResultInserter 8.39233e-05
0043 TimeModule> 1006 1 out PoolOutputModule 0.268425
0044 TimeEvent> 1006 1 0.289073
0045 Begin processing the 7th record. Run 1, Event 1007, LumiSection 666670 at 05-Feb-2009 14:01:42 CET
0046 TimeModule> 1007 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.040622
0047 TimeModule> 1007 1 TriggerResults TriggerResultInserter 8.29697e-05
0048 TimeModule> 1007 1 out PoolOutputModule 0.27238
0049 TimeEvent> 1007 1 0.314472
0050 Begin processing the 8th record. Run 1, Event 1008, LumiSection 666670 at 05-Feb-2009 14:01:42 CET
0051 TimeModule> 1008 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.017935
0052 TimeModule> 1008 1 TriggerResults TriggerResultInserter 8.29697e-05
0053 TimeModule> 1008 1 out PoolOutputModule 0.284499
0054 TimeEvent> 1008 1 0.303923
0055 Begin processing the 9th record. Run 1, Event 1009, LumiSection 666670 at 05-Feb-2009 14:01:42 CET
0056 TimeModule> 1009 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0203469
0057 TimeModule> 1009 1 TriggerResults TriggerResultInserter 8.2016e-05
0058 TimeModule> 1009 1 out PoolOutputModule 0.284675
0059 TimeEvent> 1009 1 0.306501
0060 Begin processing the 10th record. Run 1, Event 1010, LumiSection 666670 at 05-Feb-2009 14:01:43 CET
0061 TimeModule> 1010 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0426278
0062 TimeModule> 1010 1 TriggerResults TriggerResultInserter 8.2016e-05
0063 TimeModule> 1010 1 out PoolOutputModule 0.28725
0064 TimeEvent> 1010 1 0.331379
0065 Begin processing the 11th record. Run 1, Event 1011, LumiSection 666670 at 05-Feb-2009 14:01:43 CET
0066 TimeModule> 1011 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0359499
0067 TimeModule> 1011 1 TriggerResults TriggerResultInserter 8.29697e-05
0068 TimeModule> 1011 1 out PoolOutputModule 0.288272
0069 TimeEvent> 1011 1 0.325742
0070 Begin processing the 12th record. Run 1, Event 1012, LumiSection 666670 at 05-Feb-2009 14:01:43 CET
0071 TimeModule> 1012 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.056345
0072 TimeModule> 1012 1 TriggerResults TriggerResultInserter 8.2016e-05
0073 TimeModule> 1012 1 out PoolOutputModule 0.280182
0074 TimeEvent> 1012 1 0.337999
0075 Begin processing the 13th record. Run 1, Event 1013, LumiSection 666670 at 05-Feb-2009 14:01:44 CET
0076 TimeModule> 1013 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0360842
0077 TimeModule> 1013 1 TriggerResults TriggerResultInserter 9.799e-05
0078 TimeModule> 1013 1 out PoolOutputModule 0.283418
0079 TimeEvent> 1013 1 0.321057
0080 Begin processing the 14th record. Run 1, Event 1014, LumiSection 666670 at 05-Feb-2009 14:01:44 CET
0081 TimeModule> 1014 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0351338
0082 TimeModule> 1014 1 TriggerResults TriggerResultInserter 8.79765e-05
0083 TimeModule> 1014 1 out PoolOutputModule 0.264934
0084 TimeEvent> 1014 1 0.301544
0085 Begin processing the 15th record. Run 1, Event 1015, LumiSection 666670 at 05-Feb-2009 14:01:44 CET
0086 TimeModule> 1015 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0320721
0087 TimeModule> 1015 1 TriggerResults TriggerResultInserter 8.70228e-05
0088 TimeModule> 1015 1 out PoolOutputModule 0.26466
0089 TimeEvent> 1015 1 0.298224
0090 Begin processing the 16th record. Run 1, Event 1016, LumiSection 666670 at 05-Feb-2009 14:01:45 CET
0091 TimeModule> 1016 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.070272
0092 TimeModule> 1016 1 TriggerResults TriggerResultInserter 8.60691e-05
0093 TimeModule> 1016 1 out PoolOutputModule 0.258889
0094 TimeEvent> 1016 1 0.330647
0095 Begin processing the 17th record. Run 1, Event 1017, LumiSection 666670 at 05-Feb-2009 14:01:45 CET
0096 TimeModule> 1017 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0309489
0097 TimeModule> 1017 1 TriggerResults TriggerResultInserter 8.2016e-05
0098 TimeModule> 1017 1 out PoolOutputModule 0.267936
0099 TimeEvent> 1017 1 0.3004
0100 Begin processing the 18th record. Run 1, Event 1018, LumiSection 666670 at 05-Feb-2009 14:01:45 CET
0101 TimeModule> 1018 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0311391
0102 TimeModule> 1018 1 TriggerResults TriggerResultInserter 8.82149e-05
0103 TimeModule> 1018 1 out PoolOutputModule 0.267716
0104 TimeEvent> 1018 1 0.300342
0105 Begin processing the 19th record. Run 1, Event 1019, LumiSection 666670 at 05-Feb-2009 14:01:46 CET
0106 TimeModule> 1019 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0199149
0107 TimeModule> 1019 1 TriggerResults TriggerResultInserter 8.51154e-05
0108 TimeModule> 1019 1 out PoolOutputModule 0.265487
0109 TimeEvent> 1019 1 0.287131
0110 Begin processing the 20th record. Run 1, Event 1020, LumiSection 666670 at 05-Feb-2009 14:01:46 CET
0111 TimeModule> 1020 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.184695
0112 TimeModule> 1020 1 TriggerResults TriggerResultInserter 8.79765e-05
0113 TimeModule> 1020 1 out PoolOutputModule 0.367434
0114 TimeEvent> 1020 1 0.553613
0115 Begin processing the 21st record. Run 1, Event 1021, LumiSection 666670 at 05-Feb-2009 14:01:46 CET
0116 TimeModule> 1021 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.165017
0117 TimeModule> 1021 1 TriggerResults TriggerResultInserter 8.79765e-05
0118 TimeModule> 1021 1 out PoolOutputModule 0.323012
0119 TimeEvent> 1021 1 0.489507
0120 Begin processing the 22nd record. Run 1, Event 1022, LumiSection 666670 at 05-Feb-2009 14:01:47 CET
0121 TimeModule> 1022 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0524881
0122 TimeModule> 1022 1 TriggerResults TriggerResultInserter 8.79765e-05
0123 TimeModule> 1022 1 out PoolOutputModule 0.274894
0124 TimeEvent> 1022 1 0.328875
0125 Begin processing the 23rd record. Run 1, Event 1023, LumiSection 666670 at 05-Feb-2009 14:01:47 CET
0126 TimeModule> 1023 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0311019
0127 TimeModule> 1023 1 TriggerResults TriggerResultInserter 8.4877e-05
0128 TimeModule> 1023 1 out PoolOutputModule 0.283123
0129 TimeEvent> 1023 1 0.315736
0130 Begin processing the 24th record. Run 1, Event 1024, LumiSection 666670 at 05-Feb-2009 14:01:48 CET
0131 TimeModule> 1024 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0252051
0132 TimeModule> 1024 1 TriggerResults TriggerResultInserter 8.70228e-05
0133 TimeModule> 1024 1 out PoolOutputModule 0.269381
0134 TimeEvent> 1024 1 0.296056
0135 Begin processing the 25th record. Run 1, Event 1025, LumiSection 666670 at 05-Feb-2009 14:01:48 CET
0136 TimeModule> 1025 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0321741
0137 TimeModule> 1025 1 TriggerResults TriggerResultInserter 8.70228e-05
0138 TimeModule> 1025 1 out PoolOutputModule 0.272166
0139 TimeEvent> 1025 1 0.305824
0140 Begin processing the 26th record. Run 1, Event 1026, LumiSection 666670 at 05-Feb-2009 14:01:48 CET
0141 TimeModule> 1026 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0393081
0142 TimeModule> 1026 1 TriggerResults TriggerResultInserter 8.32081e-05
0143 TimeModule> 1026 1 out PoolOutputModule 0.283467
0144 TimeEvent> 1026 1 0.324254
0145 Begin processing the 27th record. Run 1, Event 1027, LumiSection 666670 at 05-Feb-2009 14:01:49 CET
0146 TimeModule> 1027 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.055825
0147 TimeModule> 1027 1 TriggerResults TriggerResultInserter 8.51154e-05
0148 TimeModule> 1027 1 out PoolOutputModule 0.270243
0149 TimeEvent> 1027 1 0.327553
0150 Begin processing the 28th record. Run 1, Event 1028, LumiSection 666670 at 05-Feb-2009 14:01:49 CET
0151 TimeModule> 1028 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.247692
0152 TimeModule> 1028 1 TriggerResults TriggerResultInserter 7.00951e-05
0153 TimeModule> 1028 1 out PoolOutputModule 0.315475
0154 TimeEvent> 1028 1 0.564086
0155 Begin processing the 29th record. Run 1, Event 1029, LumiSection 666670 at 05-Feb-2009 14:01:49 CET
0156 TimeModule> 1029 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0211959
0157 TimeModule> 1029 1 TriggerResults TriggerResultInserter 8.4877e-05
0158 TimeModule> 1029 1 out PoolOutputModule 0.27441
0159 TimeEvent> 1029 1 0.297112
0160 Begin processing the 30th record. Run 1, Event 1030, LumiSection 666670 at 05-Feb-2009 14:01:50 CET
0161 TimeModule> 1030 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0277431
0162 TimeModule> 1030 1 TriggerResults TriggerResultInserter 8.89301e-05
0163 TimeModule> 1030 1 out PoolOutputModule 0.275807
0164 TimeEvent> 1030 1 0.305062
0165 Begin processing the 31st record. Run 1, Event 1031, LumiSection 666670 at 05-Feb-2009 14:01:50 CET
0166 TimeModule> 1031 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.127565
0167 TimeModule> 1031 1 TriggerResults TriggerResultInserter 8.70228e-05
0168 TimeModule> 1031 1 out PoolOutputModule 0.266848
0169 TimeEvent> 1031 1 0.395896
0170 Begin processing the 32nd record. Run 1, Event 1032, LumiSection 666670 at 05-Feb-2009 14:01:50 CET
0171 TimeModule> 1032 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.020972
0172 TimeModule> 1032 1 TriggerResults TriggerResultInserter 8.60691e-05
0173 TimeModule> 1032 1 out PoolOutputModule 0.281262
0174 TimeEvent> 1032 1 0.303725
0175 Begin processing the 33rd record. Run 1, Event 1033, LumiSection 666670 at 05-Feb-2009 14:01:51 CET
0176 TimeModule> 1033 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.02038
0177 TimeModule> 1033 1 TriggerResults TriggerResultInserter 8.70228e-05
0178 TimeModule> 1033 1 out PoolOutputModule 0.287071
0179 TimeEvent> 1033 1 0.308948
0180 Begin processing the 34th record. Run 1, Event 1034, LumiSection 666670 at 05-Feb-2009 14:01:51 CET
0181 TimeModule> 1034 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0566821
0182 TimeModule> 1034 1 TriggerResults TriggerResultInserter 8.79765e-05
0183 TimeModule> 1034 1 out PoolOutputModule 0.263596
0184 TimeEvent> 1034 1 0.32177
0185 Begin processing the 35th record. Run 1, Event 1035, LumiSection 666670 at 05-Feb-2009 14:01:51 CET
0186 TimeModule> 1035 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.160322
0187 TimeModule> 1035 1 TriggerResults TriggerResultInserter 8.82149e-05
0188 TimeModule> 1035 1 out PoolOutputModule 0.289845
0189 TimeEvent> 1035 1 0.451662
0190 Begin processing the 36th record. Run 1, Event 1036, LumiSection 666670 at 05-Feb-2009 14:01:52 CET
0191 TimeModule> 1036 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.024817
0192 TimeModule> 1036 1 TriggerResults TriggerResultInserter 8.60691e-05
0193 TimeModule> 1036 1 out PoolOutputModule 0.266806
0194 TimeEvent> 1036 1 0.293133
0195 Begin processing the 37th record. Run 1, Event 1037, LumiSection 666670 at 05-Feb-2009 14:01:52 CET
0196 TimeModule> 1037 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0908542
0197 TimeModule> 1037 1 TriggerResults TriggerResultInserter 8.51154e-05
0198 TimeModule> 1037 1 out PoolOutputModule 0.269701
0199 TimeEvent> 1037 1 0.362046
0200 Begin processing the 38th record. Run 1, Event 1038, LumiSection 666670 at 05-Feb-2009 14:01:53 CET
0201 TimeModule> 1038 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0634301
0202 TimeModule> 1038 1 TriggerResults TriggerResultInserter 8.60691e-05
0203 TimeModule> 1038 1 out PoolOutputModule 0.304426
0204 TimeEvent> 1038 1 0.369344
0205 Begin processing the 39th record. Run 1, Event 1039, LumiSection 666670 at 05-Feb-2009 14:01:53 CET
0206 TimeModule> 1039 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0200942
0207 TimeModule> 1039 1 TriggerResults TriggerResultInserter 8.60691e-05
0208 TimeModule> 1039 1 out PoolOutputModule 0.375255
0209 TimeEvent> 1039 1 0.397042
0210 Begin processing the 40th record. Run 1, Event 1040, LumiSection 666670 at 05-Feb-2009 14:01:53 CET
0211 TimeModule> 1040 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.119988
0212 TimeModule> 1040 1 TriggerResults TriggerResultInserter 7.70092e-05
0213 TimeModule> 1040 1 out PoolOutputModule 0.679589
0214 TimeEvent> 1040 1 0.800613
0215 Begin processing the 41st record. Run 1, Event 1041, LumiSection 666670 at 05-Feb-2009 14:01:54 CET
0216 TimeModule> 1041 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0206881
0217 TimeModule> 1041 1 TriggerResults TriggerResultInserter 8.70228e-05
0218 TimeModule> 1041 1 out PoolOutputModule 0.344274
0219 TimeEvent> 1041 1 0.366456
0220 Begin processing the 42nd record. Run 1, Event 1042, LumiSection 666670 at 05-Feb-2009 14:01:54 CET
0221 TimeModule> 1042 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0226619
0222 TimeModule> 1042 1 TriggerResults TriggerResultInserter 8.60691e-05
0223 TimeModule> 1042 1 out PoolOutputModule 0.275784
0224 TimeEvent> 1042 1 0.299931
0225 Begin processing the 43rd record. Run 1, Event 1043, LumiSection 666670 at 05-Feb-2009 14:01:55 CET
0226 TimeModule> 1043 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0207989
0227 TimeModule> 1043 1 TriggerResults TriggerResultInserter 8.70228e-05
0228 TimeModule> 1043 1 out PoolOutputModule 0.281346
0229 TimeEvent> 1043 1 0.303634
0230 Begin processing the 44th record. Run 1, Event 1044, LumiSection 666670 at 05-Feb-2009 14:01:55 CET
0231 TimeModule> 1044 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0861909
0232 TimeModule> 1044 1 TriggerResults TriggerResultInserter 8.58307e-05
0233 TimeModule> 1044 1 out PoolOutputModule 0.521263
0234 TimeEvent> 1044 1 0.608948
0235 Begin processing the 45th record. Run 1, Event 1045, LumiSection 666670 at 05-Feb-2009 14:01:56 CET
0236 TimeModule> 1045 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0783658
0237 TimeModule> 1045 1 TriggerResults TriggerResultInserter 8.60691e-05
0238 TimeModule> 1045 1 out PoolOutputModule 0.281627
0239 TimeEvent> 1045 1 0.361487
0240 Begin processing the 46th record. Run 1, Event 1046, LumiSection 666670 at 05-Feb-2009 14:01:56 CET
0241 TimeModule> 1046 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.147087
0242 TimeModule> 1046 1 TriggerResults TriggerResultInserter 8.70228e-05
0243 TimeModule> 1046 1 out PoolOutputModule 0.295765
0244 TimeEvent> 1046 1 0.44416
0245 Begin processing the 47th record. Run 1, Event 1047, LumiSection 666670 at 05-Feb-2009 14:01:57 CET
0246 TimeModule> 1047 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.164391
0247 TimeModule> 1047 1 TriggerResults TriggerResultInserter 9.10759e-05
0248 TimeModule> 1047 1 out PoolOutputModule 0.282382
0249 TimeEvent> 1047 1 0.448263
0250 Begin processing the 48th record. Run 1, Event 1048, LumiSection 666670 at 05-Feb-2009 14:01:57 CET
0251 TimeModule> 1048 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.02652
0252 TimeModule> 1048 1 TriggerResults TriggerResultInserter 8.79765e-05
0253 TimeModule> 1048 1 out PoolOutputModule 0.278639
0254 TimeEvent> 1048 1 0.306674
0255 Begin processing the 49th record. Run 1, Event 1049, LumiSection 666670 at 05-Feb-2009 14:01:57 CET
0256 TimeModule> 1049 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0242851
0257 TimeModule> 1049 1 TriggerResults TriggerResultInserter 8.89301e-05
0258 TimeModule> 1049 1 out PoolOutputModule 0.266952
0259 TimeEvent> 1049 1 0.292784
0260 Begin processing the 50th record. Run 1, Event 1050, LumiSection 666670 at 05-Feb-2009 14:01:58 CET
0261 TimeModule> 1050 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0229619
0262 TimeModule> 1050 1 TriggerResults TriggerResultInserter 8.70228e-05
0263 TimeModule> 1050 1 out PoolOutputModule 0.276813
0264 TimeEvent> 1050 1 0.30125
0265 Begin processing the 51st record. Run 1, Event 1051, LumiSection 666670 at 05-Feb-2009 14:01:58 CET
0266 TimeModule> 1051 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0194972
0267 TimeModule> 1051 1 TriggerResults TriggerResultInserter 9.10759e-05
0268 TimeModule> 1051 1 out PoolOutputModule 0.432649
0269 TimeEvent> 1051 1 0.453612
0270 Begin processing the 52nd record. Run 1, Event 1052, LumiSection 666670 at 05-Feb-2009 14:01:58 CET
0271 TimeModule> 1052 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0305271
0272 TimeModule> 1052 1 TriggerResults TriggerResultInserter 8.39233e-05
0273 TimeModule> 1052 1 out PoolOutputModule 0.28233
0274 TimeEvent> 1052 1 0.314346
0275 Begin processing the 53rd record. Run 1, Event 1053, LumiSection 666670 at 05-Feb-2009 14:01:59 CET
0276 TimeModule> 1053 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0537059
0277 TimeModule> 1053 1 TriggerResults TriggerResultInserter 8.70228e-05
0278 TimeModule> 1053 1 out PoolOutputModule 0.302655
0279 TimeEvent> 1053 1 0.357851
0280 Begin processing the 54th record. Run 1, Event 1054, LumiSection 666670 at 05-Feb-2009 14:01:59 CET
0281 TimeModule> 1054 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.02141
0282 TimeModule> 1054 1 TriggerResults TriggerResultInserter 8.4877e-05
0283 TimeModule> 1054 1 out PoolOutputModule 0.279471
0284 TimeEvent> 1054 1 0.302349
0285 Begin processing the 55th record. Run 1, Event 1055, LumiSection 666670 at 05-Feb-2009 14:01:59 CET
0286 TimeModule> 1055 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.032624
0287 TimeModule> 1055 1 TriggerResults TriggerResultInserter 8.39233e-05
0288 TimeModule> 1055 1 out PoolOutputModule 0.273778
0289 TimeEvent> 1055 1 0.307886
0290 Begin processing the 56th record. Run 1, Event 1056, LumiSection 666670 at 05-Feb-2009 14:02:00 CET
0291 TimeModule> 1056 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0475478
0292 TimeModule> 1056 1 TriggerResults TriggerResultInserter 8.39233e-05
0293 TimeModule> 1056 1 out PoolOutputModule 0.270859
0294 TimeEvent> 1056 1 0.3199
0295 Begin processing the 57th record. Run 1, Event 1057, LumiSection 666670 at 05-Feb-2009 14:02:00 CET
0296 TimeModule> 1057 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0851381
0297 TimeModule> 1057 1 TriggerResults TriggerResultInserter 8.70228e-05
0298 TimeModule> 1057 1 out PoolOutputModule 0.258981
0299 TimeEvent> 1057 1 0.345605
0300 Begin processing the 58th record. Run 1, Event 1058, LumiSection 666670 at 05-Feb-2009 14:02:00 CET
0301 TimeModule> 1058 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0751469
0302 TimeModule> 1058 1 TriggerResults TriggerResultInserter 8.4877e-05
0303 TimeModule> 1058 1 out PoolOutputModule 0.276997
0304 TimeEvent> 1058 1 0.353618
0305 Begin processing the 59th record. Run 1, Event 1059, LumiSection 666670 at 05-Feb-2009 14:02:01 CET
0306 TimeModule> 1059 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.033267
0307 TimeModule> 1059 1 TriggerResults TriggerResultInserter 8.60691e-05
0308 TimeModule> 1059 1 out PoolOutputModule 0.267635
0309 TimeEvent> 1059 1 0.302582
0310 Begin processing the 60th record. Run 1, Event 1060, LumiSection 666670 at 05-Feb-2009 14:02:01 CET
0311 TimeModule> 1060 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0197439
0312 TimeModule> 1060 1 TriggerResults TriggerResultInserter 8.58307e-05
0313 TimeModule> 1060 1 out PoolOutputModule 0.27543
0314 TimeEvent> 1060 1 0.296644
0315 Begin processing the 61st record. Run 1, Event 1061, LumiSection 666670 at 05-Feb-2009 14:02:01 CET
0316 TimeModule> 1061 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.037375
0317 TimeModule> 1061 1 TriggerResults TriggerResultInserter 8.39233e-05
0318 TimeModule> 1061 1 out PoolOutputModule 0.253081
0319 TimeEvent> 1061 1 0.291967
0320 Begin processing the 62nd record. Run 1, Event 1062, LumiSection 666670 at 05-Feb-2009 14:02:02 CET
0321 TimeModule> 1062 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.131446
0322 TimeModule> 1062 1 TriggerResults TriggerResultInserter 8.70228e-05
0323 TimeModule> 1062 1 out PoolOutputModule 0.317073
0324 TimeEvent> 1062 1 0.450006
0325 Begin processing the 63rd record. Run 1, Event 1063, LumiSection 666670 at 05-Feb-2009 14:02:02 CET
0326 TimeModule> 1063 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0416632
0327 TimeModule> 1063 1 TriggerResults TriggerResultInserter 9.01222e-05
0328 TimeModule> 1063 1 out PoolOutputModule 0.269114
0329 TimeEvent> 1063 1 0.312254
0330 Begin processing the 64th record. Run 1, Event 1064, LumiSection 666670 at 05-Feb-2009 14:02:02 CET
0331 TimeModule> 1064 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0221331
0332 TimeModule> 1064 1 TriggerResults TriggerResultInserter 8.70228e-05
0333 TimeModule> 1064 1 out PoolOutputModule 0.261972
0334 TimeEvent> 1064 1 0.285593
0335 Begin processing the 65th record. Run 1, Event 1065, LumiSection 666670 at 05-Feb-2009 14:02:03 CET
0336 TimeModule> 1065 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0207372
0337 TimeModule> 1065 1 TriggerResults TriggerResultInserter 8.58307e-05
0338 TimeModule> 1065 1 out PoolOutputModule 0.270385
0339 TimeEvent> 1065 1 0.292584
0340 Begin processing the 66th record. Run 1, Event 1066, LumiSection 666670 at 05-Feb-2009 14:02:03 CET
0341 TimeModule> 1066 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.088012
0342 TimeModule> 1066 1 TriggerResults TriggerResultInserter 8.58307e-05
0343 TimeModule> 1066 1 out PoolOutputModule 0.274976
0344 TimeEvent> 1066 1 0.364463
0345 Begin processing the 67th record. Run 1, Event 1067, LumiSection 666670 at 05-Feb-2009 14:02:03 CET
0346 TimeModule> 1067 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0210021
0347 TimeModule> 1067 1 TriggerResults TriggerResultInserter 8.58307e-05
0348 TimeModule> 1067 1 out PoolOutputModule 0.279498
0349 TimeEvent> 1067 1 0.302021
0350 Begin processing the 68th record. Run 1, Event 1068, LumiSection 666670 at 05-Feb-2009 14:02:04 CET
0351 TimeModule> 1068 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.611998
0352 TimeModule> 1068 1 TriggerResults TriggerResultInserter 8.32081e-05
0353 TimeModule> 1068 1 out PoolOutputModule 0.320368
0354 TimeEvent> 1068 1 0.933315
0355 Begin processing the 69th record. Run 1, Event 1069, LumiSection 666670 at 05-Feb-2009 14:02:05 CET
0356 TimeModule> 1069 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0928299
0357 TimeModule> 1069 1 TriggerResults TriggerResultInserter 8.89301e-05
0358 TimeModule> 1069 1 out PoolOutputModule 0.269497
0359 TimeEvent> 1069 1 0.363811
0360 Begin processing the 70th record. Run 1, Event 1070, LumiSection 666670 at 05-Feb-2009 14:02:05 CET
0361 TimeModule> 1070 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.022646
0362 TimeModule> 1070 1 TriggerResults TriggerResultInserter 8.70228e-05
0363 TimeModule> 1070 1 out PoolOutputModule 0.278942
0364 TimeEvent> 1070 1 0.303057
0365 Begin processing the 71st record. Run 1, Event 1071, LumiSection 666670 at 05-Feb-2009 14:02:05 CET
0366 TimeModule> 1071 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0536439
0367 TimeModule> 1071 1 TriggerResults TriggerResultInserter 8.79765e-05
0368 TimeModule> 1071 1 out PoolOutputModule 0.263252
0369 TimeEvent> 1071 1 0.318389
0370 Begin processing the 72nd record. Run 1, Event 1072, LumiSection 666670 at 05-Feb-2009 14:02:06 CET
0371 TimeModule> 1072 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0208941
0372 TimeModule> 1072 1 TriggerResults TriggerResultInserter 8.79765e-05
0373 TimeModule> 1072 1 out PoolOutputModule 0.274144
0374 TimeEvent> 1072 1 0.296516
0375 Begin processing the 73rd record. Run 1, Event 1073, LumiSection 666670 at 05-Feb-2009 14:02:06 CET
0376 TimeModule> 1073 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.201256
0377 TimeModule> 1073 1 TriggerResults TriggerResultInserter 6.8903e-05
0378 TimeModule> 1073 1 out PoolOutputModule 0.267521
0379 TimeEvent> 1073 1 0.469686
0380 Begin processing the 74th record. Run 1, Event 1074, LumiSection 666670 at 05-Feb-2009 14:02:06 CET
0381 TimeModule> 1074 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0311031
0382 TimeModule> 1074 1 TriggerResults TriggerResultInserter 8.39233e-05
0383 TimeModule> 1074 1 out PoolOutputModule 0.282994
0384 TimeEvent> 1074 1 0.315568
0385 Begin processing the 75th record. Run 1, Event 1075, LumiSection 666670 at 05-Feb-2009 14:02:07 CET
0386 TimeModule> 1075 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0302079
0387 TimeModule> 1075 1 TriggerResults TriggerResultInserter 8.51154e-05
0388 TimeModule> 1075 1 out PoolOutputModule 0.268995
0389 TimeEvent> 1075 1 0.300679
0390 Begin processing the 76th record. Run 1, Event 1076, LumiSection 666670 at 05-Feb-2009 14:02:07 CET
0391 TimeModule> 1076 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.020365
0392 TimeModule> 1076 1 TriggerResults TriggerResultInserter 8.39233e-05
0393 TimeModule> 1076 1 out PoolOutputModule 0.272103
0394 TimeEvent> 1076 1 0.293932
0395 Begin processing the 77th record. Run 1, Event 1077, LumiSection 666670 at 05-Feb-2009 14:02:07 CET
0396 TimeModule> 1077 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.046407
0397 TimeModule> 1077 1 TriggerResults TriggerResultInserter 8.39233e-05
0398 TimeModule> 1077 1 out PoolOutputModule 0.298876
0399 TimeEvent> 1077 1 0.34675
0400 Begin processing the 78th record. Run 1, Event 1078, LumiSection 666670 at 05-Feb-2009 14:02:08 CET
0401 TimeModule> 1078 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.049983
0402 TimeModule> 1078 1 TriggerResults TriggerResultInserter 8.58307e-05
0403 TimeModule> 1078 1 out PoolOutputModule 0.325327
0404 TimeEvent> 1078 1 0.376774
0405 Begin processing the 79th record. Run 1, Event 1079, LumiSection 666670 at 05-Feb-2009 14:02:08 CET
0406 TimeModule> 1079 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0773721
0407 TimeModule> 1079 1 TriggerResults TriggerResultInserter 8.70228e-05
0408 TimeModule> 1079 1 out PoolOutputModule 0.384702
0409 TimeEvent> 1079 1 0.463753
0410 Begin processing the 80th record. Run 1, Event 1080, LumiSection 666670 at 05-Feb-2009 14:02:08 CET
0411 TimeModule> 1080 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0496459
0412 TimeModule> 1080 1 TriggerResults TriggerResultInserter 8.51154e-05
0413 TimeModule> 1080 1 out PoolOutputModule 0.268139
0414 TimeEvent> 1080 1 0.319264
0415 Begin processing the 81st record. Run 1, Event 1081, LumiSection 666670 at 05-Feb-2009 14:02:09 CET
0416 TimeModule> 1081 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.020757
0417 TimeModule> 1081 1 TriggerResults TriggerResultInserter 8.10623e-05
0418 TimeModule> 1081 1 out PoolOutputModule 0.283131
0419 TimeEvent> 1081 1 0.30535
0420 Begin processing the 82nd record. Run 1, Event 1082, LumiSection 666670 at 05-Feb-2009 14:02:09 CET
0421 TimeModule> 1082 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.038332
0422 TimeModule> 1082 1 TriggerResults TriggerResultInserter 8.2016e-05
0423 TimeModule> 1082 1 out PoolOutputModule 0.27343
0424 TimeEvent> 1082 1 0.313229
0425 Begin processing the 83rd record. Run 1, Event 1083, LumiSection 666670 at 05-Feb-2009 14:02:09 CET
0426 TimeModule> 1083 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.020772
0427 TimeModule> 1083 1 TriggerResults TriggerResultInserter 8.4877e-05
0428 TimeModule> 1083 1 out PoolOutputModule 0.263879
0429 TimeEvent> 1083 1 0.286118
0430 Begin processing the 84th record. Run 1, Event 1084, LumiSection 666670 at 05-Feb-2009 14:02:10 CET
0431 TimeModule> 1084 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0237138
0432 TimeModule> 1084 1 TriggerResults TriggerResultInserter 8.70228e-05
0433 TimeModule> 1084 1 out PoolOutputModule 0.280961
0434 TimeEvent> 1084 1 0.306142
0435 Begin processing the 85th record. Run 1, Event 1085, LumiSection 666670 at 05-Feb-2009 14:02:10 CET
0436 TimeModule> 1085 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0468311
0437 TimeModule> 1085 1 TriggerResults TriggerResultInserter 8.4877e-05
0438 TimeModule> 1085 1 out PoolOutputModule 0.276078
0439 TimeEvent> 1085 1 0.324372
0440 Begin processing the 86th record. Run 1, Event 1086, LumiSection 666670 at 05-Feb-2009 14:02:10 CET
0441 TimeModule> 1086 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0551231
0442 TimeModule> 1086 1 TriggerResults TriggerResultInserter 8.39233e-05
0443 TimeModule> 1086 1 out PoolOutputModule 0.276501
0444 TimeEvent> 1086 1 0.333098
0445 Begin processing the 87th record. Run 1, Event 1087, LumiSection 666670 at 05-Feb-2009 14:02:11 CET
0446 TimeModule> 1087 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.091773
0447 TimeModule> 1087 1 TriggerResults TriggerResultInserter 8.70228e-05
0448 TimeModule> 1087 1 out PoolOutputModule 0.273917
0449 TimeEvent> 1087 1 0.367226
0450 Begin processing the 88th record. Run 1, Event 1088, LumiSection 666670 at 05-Feb-2009 14:02:11 CET
0451 TimeModule> 1088 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.035032
0452 TimeModule> 1088 1 TriggerResults TriggerResultInserter 8.82149e-05
0453 TimeModule> 1088 1 out PoolOutputModule 0.264994
0454 TimeEvent> 1088 1 0.301569
0455 Begin processing the 89th record. Run 1, Event 1089, LumiSection 666670 at 05-Feb-2009 14:02:11 CET
0456 TimeModule> 1089 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.134938
0457 TimeModule> 1089 1 TriggerResults TriggerResultInserter 8.89301e-05
0458 TimeModule> 1089 1 out PoolOutputModule 0.299358
0459 TimeEvent> 1089 1 0.435788
0460 Begin processing the 90th record. Run 1, Event 1090, LumiSection 666670 at 05-Feb-2009 14:02:12 CET
0461 TimeModule> 1090 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0318959
0462 TimeModule> 1090 1 TriggerResults TriggerResultInserter 8.98838e-05
0463 TimeModule> 1090 1 out PoolOutputModule 0.287772
0464 TimeEvent> 1090 1 0.321162
0465 Begin processing the 91st record. Run 1, Event 1091, LumiSection 666670 at 05-Feb-2009 14:02:12 CET
0466 TimeModule> 1091 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.040204
0467 TimeModule> 1091 1 TriggerResults TriggerResultInserter 8.70228e-05
0468 TimeModule> 1091 1 out PoolOutputModule 0.263766
0469 TimeEvent> 1091 1 0.305455
0470 Begin processing the 92nd record. Run 1, Event 1092, LumiSection 666670 at 05-Feb-2009 14:02:12 CET
0471 TimeModule> 1092 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0373261
0472 TimeModule> 1092 1 TriggerResults TriggerResultInserter 8.51154e-05
0473 TimeModule> 1092 1 out PoolOutputModule 0.257798
0474 TimeEvent> 1092 1 0.296612
0475 Begin processing the 93rd record. Run 1, Event 1093, LumiSection 666670 at 05-Feb-2009 14:02:13 CET
0476 TimeModule> 1093 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.0245209
0477 TimeModule> 1093 1 TriggerResults TriggerResultInserter 8.32081e-05
0478 TimeModule> 1093 1 out PoolOutputModule 0.28693
0479 TimeEvent> 1093 1 0.312926
0480 Begin processing the 94th record. Run 1, Event 1094, LumiSection 666670 at 05-Feb-2009 14:02:13 CET
0481 TimeModule> 1094 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.023124
0482 TimeModule> 1094 1 TriggerResults TriggerResultInserter 8.39233e-05
0483 TimeModule> 1094 1 out PoolOutputModule 0.266521
0484 TimeEvent> 1094 1 0.291119
0485 Begin processing the 95th record. Run 1, Event 1095, LumiSection 666670 at 05-Feb-2009 14:02:13 CET
0486 TimeModule> 1095 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.08426
0487 TimeModule> 1095 1 TriggerResults TriggerResultInserter 9.01222e-05
0488 TimeModule> 1095 1 out PoolOutputModule 0.27655
0489 TimeEvent> 1095 1 0.362312
0490 Begin processing the 96th record. Run 1, Event 1096, LumiSection 666670 at 05-Feb-2009 14:02:14 CET
0491 TimeModule> 1096 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.045954
0492 TimeModule> 1096 1 TriggerResults TriggerResultInserter 8.91685e-05
0493 TimeModule> 1096 1 out PoolOutputModule 0.291536
0494 TimeEvent> 1096 1 0.338974
0495 Begin processing the 97th record. Run 1, Event 1097, LumiSection 666670 at 05-Feb-2009 14:02:14 CET
0496 TimeModule> 1097 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.018038
0497 TimeModule> 1097 1 TriggerResults TriggerResultInserter 8.41618e-05
0498 TimeModule> 1097 1 out PoolOutputModule 0.26715
0499 TimeEvent> 1097 1 0.286669
0500 Begin processing the 98th record. Run 1, Event 1098, LumiSection 666670 at 05-Feb-2009 14:02:14 CET
0501 TimeModule> 1098 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.111484
0502 TimeModule> 1098 1 TriggerResults TriggerResultInserter 8.70228e-05
0503 TimeModule> 1098 1 out PoolOutputModule 0.270582
0504 TimeEvent> 1098 1 0.383546
0505 Begin processing the 99th record. Run 1, Event 1099, LumiSection 666670 at 05-Feb-2009 14:02:15 CET
0506 TimeModule> 1099 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.596014
0507 TimeModule> 1099 1 TriggerResults TriggerResultInserter 6.91414e-05
0508 TimeModule> 1099 1 out PoolOutputModule 0.331938
0509 TimeEvent> 1099 1 0.929091
0510 Begin processing the 100th record. Run 1, Event 1100, LumiSection 666670 at 05-Feb-2009 14:02:16 CET
0511 TimeModule> 1100 1 simEcalTriggerPrimitiveDigis EcalTrigPrimProducer 0.089417
0512 TimeModule> 1100 1 TriggerResults TriggerResultInserter 8.82149e-05
0513 TimeModule> 1100 1 out PoolOutputModule 0.300594
0514 TimeEvent> 1100 1 0.391538
0515 05-Feb-2009 14:02:16 CET Closed file rfio:/castor/cern.ch/cms/store/relval/CMSSW_3_0_0_pre6/RelValSingleElectronPt35/GEN-SIM-DIGI-RAW-HLTDEBUG/IDEAL_30X_v1/0005/28116A15-E9DD-DD11-9BA6-001617E30F4C.root
0516 TimeReport> Time report complete in 37.9839 seconds
0517 Time Summary:
0518 Min: 0.285593
0519 Max: 1.09188
0520 Avg: 0.379839
0521
0522
0523 =============================================
0524
0525 MessageLogger Summary
0526
0527 type category sev module subroutine count total
0528 ---- -------------------- -- ---------------- ---------------- ----- -----
0529 1 TimeEvent -s PostModule 100 100
0530 2 TimeModule -s EcalTrigPrimProd 100 100
0531 3 TimeModule -s PoolOutputModule 100 100
0532 4 TimeModule -s TriggerResultIns 100 100
0533 5 TimeReport -s 1 1
0534 6 TimeReport -s PostModule 1 1
0535 7 fileAction -s PoolSource:sourc 2 2
0536 8 fileAction -s PostModule 1 1
0537
0538 type category Examples: run/evt run/evt run/evt
0539 ---- -------------------- ---------------- ---------------- ----------------
0540 1 TimeEvent 1/1001 1/1002 1/1100
0541 2 TimeModule 1/1001 1/1002 1/1100
0542 3 TimeModule 1/1001 1/1002 1/1100
0543 4 TimeModule 1/1001 1/1002 1/1100
0544 5 TimeReport BeforeEvents
0545 6 TimeReport 1/1100
0546 7 fileAction pre-events pre-events
0547 8 fileAction 1/1100
0548
0549 Severity # Occurrences Total Occurrences
0550 -------- ------------- -----------------
0551 System 405 405
0552 ===================> Step2: executing EDAnalyser (readTPG_suppressed_cfg.py) to create histos in histos.root
0553 05-Feb-2009 14:02:20 CET Initiating request to open file file:TrigPrim_suppressed.root
0554 05-Feb-2009 14:02:21 CET Successfully opened file file:TrigPrim_suppressed.root
0555 title = Barrel_ttf
0556 title = Endcap_ttf
0557 TimeReport> Report activated
0558 TimeReport> Report columns headings for events: eventnum runnum timetaken
0559 TimeReport> Report columns headings for modules: eventnum runnum modulelabel modulename timetaken
0560 Begin processing the 1st record. Run 1, Event 1001, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0561 TimeModule> 1001 1 tpgcheck TPGCheck 0.0464022
0562 TimeModule> 1001 1 TriggerResults TriggerResultInserter 0.00353694
0563 TimeEvent> 1001 1 0.0513909
0564 Begin processing the 2nd record. Run 1, Event 1002, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0565 TimeModule> 1002 1 tpgcheck TPGCheck 0.020673
0566 TimeModule> 1002 1 TriggerResults TriggerResultInserter 0.000255108
0567 TimeEvent> 1002 1 0.0215609
0568 Begin processing the 3rd record. Run 1, Event 1003, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0569 TimeModule> 1003 1 tpgcheck TPGCheck 0.0186942
0570 TimeModule> 1003 1 TriggerResults TriggerResultInserter 0.000235081
0571 TimeEvent> 1003 1 0.0195429
0572 Begin processing the 4th record. Run 1, Event 1004, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0573 TimeModule> 1004 1 tpgcheck TPGCheck 0.0190701
0574 TimeModule> 1004 1 TriggerResults TriggerResultInserter 0.000236988
0575 TimeEvent> 1004 1 0.0199261
0576 Begin processing the 5th record. Run 1, Event 1005, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0577 TimeModule> 1005 1 tpgcheck TPGCheck 0.020092
0578 TimeModule> 1005 1 TriggerResults TriggerResultInserter 0.000250816
0579 TimeEvent> 1005 1 0.0210099
0580 Begin processing the 6th record. Run 1, Event 1006, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0581 TimeModule> 1006 1 tpgcheck TPGCheck 0.020956
0582 TimeModule> 1006 1 TriggerResults TriggerResultInserter 0.000241995
0583 TimeEvent> 1006 1 0.0218759
0584 Begin processing the 7th record. Run 1, Event 1007, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0585 TimeModule> 1007 1 tpgcheck TPGCheck 0.0193501
0586 TimeModule> 1007 1 TriggerResults TriggerResultInserter 0.000247002
0587 TimeEvent> 1007 1 0.0202122
0588 Begin processing the 8th record. Run 1, Event 1008, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0589 TimeModule> 1008 1 tpgcheck TPGCheck 0.0199151
0590 TimeModule> 1008 1 TriggerResults TriggerResultInserter 0.00037694
0591 TimeEvent> 1008 1 0.020911
0592 Begin processing the 9th record. Run 1, Event 1009, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0593 TimeModule> 1009 1 tpgcheck TPGCheck 0.0198371
0594 TimeModule> 1009 1 TriggerResults TriggerResultInserter 0.000237942
0595 TimeEvent> 1009 1 0.0207071
0596 Begin processing the 10th record. Run 1, Event 1010, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0597 TimeModule> 1010 1 tpgcheck TPGCheck 0.021069
0598 TimeModule> 1010 1 TriggerResults TriggerResultInserter 0.000236988
0599 TimeEvent> 1010 1 0.0219421
0600 Begin processing the 11th record. Run 1, Event 1011, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0601 TimeModule> 1011 1 tpgcheck TPGCheck 0.0196872
0602 TimeModule> 1011 1 TriggerResults TriggerResultInserter 0.000241995
0603 TimeEvent> 1011 1 0.02054
0604 Begin processing the 12th record. Run 1, Event 1012, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0605 TimeModule> 1012 1 tpgcheck TPGCheck 0.021359
0606 TimeModule> 1012 1 TriggerResults TriggerResultInserter 0.000241041
0607 TimeEvent> 1012 1 0.0222259
0608 Begin processing the 13th record. Run 1, Event 1013, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0609 TimeModule> 1013 1 tpgcheck TPGCheck 0.0198519
0610 TimeModule> 1013 1 TriggerResults TriggerResultInserter 0.000243902
0611 TimeEvent> 1013 1 0.0207391
0612 Begin processing the 14th record. Run 1, Event 1014, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0613 TimeModule> 1014 1 tpgcheck TPGCheck 0.019563
0614 TimeModule> 1014 1 TriggerResults TriggerResultInserter 0.000243902
0615 TimeEvent> 1014 1 0.020431
0616 Begin processing the 15th record. Run 1, Event 1015, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0617 TimeModule> 1015 1 tpgcheck TPGCheck 0.018714
0618 TimeModule> 1015 1 TriggerResults TriggerResultInserter 0.000380993
0619 TimeEvent> 1015 1 0.0197091
0620 Begin processing the 16th record. Run 1, Event 1016, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0621 TimeModule> 1016 1 tpgcheck TPGCheck 0.0197389
0622 TimeModule> 1016 1 TriggerResults TriggerResultInserter 0.000247002
0623 TimeEvent> 1016 1 0.0206141
0624 Begin processing the 17th record. Run 1, Event 1017, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0625 TimeModule> 1017 1 tpgcheck TPGCheck 0.020576
0626 TimeModule> 1017 1 TriggerResults TriggerResultInserter 0.000239134
0627 TimeEvent> 1017 1 0.0214419
0628 Begin processing the 18th record. Run 1, Event 1018, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0629 TimeModule> 1018 1 tpgcheck TPGCheck 0.018301
0630 TimeModule> 1018 1 TriggerResults TriggerResultInserter 0.000236988
0631 TimeEvent> 1018 1 0.019171
0632 Begin processing the 19th record. Run 1, Event 1019, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0633 TimeModule> 1019 1 tpgcheck TPGCheck 0.019908
0634 TimeModule> 1019 1 TriggerResults TriggerResultInserter 0.00024581
0635 TimeEvent> 1019 1 0.020772
0636 Begin processing the 20th record. Run 1, Event 1020, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0637 TimeModule> 1020 1 tpgcheck TPGCheck 0.0195031
0638 TimeModule> 1020 1 TriggerResults TriggerResultInserter 0.000236034
0639 TimeEvent> 1020 1 0.0203509
0640 Begin processing the 21st record. Run 1, Event 1021, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0641 TimeModule> 1021 1 tpgcheck TPGCheck 0.0192242
0642 TimeModule> 1021 1 TriggerResults TriggerResultInserter 0.000240803
0643 TimeEvent> 1021 1 0.0200629
0644 Begin processing the 22nd record. Run 1, Event 1022, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0645 TimeModule> 1022 1 tpgcheck TPGCheck 0.0195742
0646 TimeModule> 1022 1 TriggerResults TriggerResultInserter 0.000380039
0647 TimeEvent> 1022 1 0.020613
0648 Begin processing the 23rd record. Run 1, Event 1023, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0649 TimeModule> 1023 1 tpgcheck TPGCheck 0.0204651
0650 TimeModule> 1023 1 TriggerResults TriggerResultInserter 0.000236988
0651 TimeEvent> 1023 1 0.0213621
0652 Begin processing the 24th record. Run 1, Event 1024, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0653 TimeModule> 1024 1 tpgcheck TPGCheck 0.019964
0654 TimeModule> 1024 1 TriggerResults TriggerResultInserter 0.000236034
0655 TimeEvent> 1024 1 0.020813
0656 Begin processing the 25th record. Run 1, Event 1025, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0657 TimeModule> 1025 1 tpgcheck TPGCheck 0.0191438
0658 TimeModule> 1025 1 TriggerResults TriggerResultInserter 0.000231981
0659 TimeEvent> 1025 1 0.019989
0660 Begin processing the 26th record. Run 1, Event 1026, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0661 TimeModule> 1026 1 tpgcheck TPGCheck 0.0197039
0662 TimeModule> 1026 1 TriggerResults TriggerResultInserter 0.000236034
0663 TimeEvent> 1026 1 0.0205562
0664 Begin processing the 27th record. Run 1, Event 1027, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0665 TimeModule> 1027 1 tpgcheck TPGCheck 0.0207851
0666 TimeModule> 1027 1 TriggerResults TriggerResultInserter 0.000237226
0667 TimeEvent> 1027 1 0.021647
0668 Begin processing the 28th record. Run 1, Event 1028, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0669 TimeModule> 1028 1 tpgcheck TPGCheck 0.0191481
0670 TimeModule> 1028 1 TriggerResults TriggerResultInserter 0.000236988
0671 TimeEvent> 1028 1 0.02
0672 Begin processing the 29th record. Run 1, Event 1029, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0673 TimeModule> 1029 1 tpgcheck TPGCheck 0.0187469
0674 TimeModule> 1029 1 TriggerResults TriggerResultInserter 0.000374079
0675 TimeEvent> 1029 1 0.019742
0676 Begin processing the 30th record. Run 1, Event 1030, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0677 TimeModule> 1030 1 tpgcheck TPGCheck 0.0200312
0678 TimeModule> 1030 1 TriggerResults TriggerResultInserter 0.000236034
0679 TimeEvent> 1030 1 0.0208759
0680 Begin processing the 31st record. Run 1, Event 1031, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0681 TimeModule> 1031 1 tpgcheck TPGCheck 0.019583
0682 TimeModule> 1031 1 TriggerResults TriggerResultInserter 0.000248909
0683 TimeEvent> 1031 1 0.020443
0684 Begin processing the 32nd record. Run 1, Event 1032, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0685 TimeModule> 1032 1 tpgcheck TPGCheck 0.0193999
0686 TimeModule> 1032 1 TriggerResults TriggerResultInserter 0.000250101
0687 TimeEvent> 1032 1 0.020272
0688 Begin processing the 33rd record. Run 1, Event 1033, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0689 TimeModule> 1033 1 tpgcheck TPGCheck 0.0193651
0690 TimeModule> 1033 1 TriggerResults TriggerResultInserter 0.000232935
0691 TimeEvent> 1033 1 0.0202081
0692 Begin processing the 34th record. Run 1, Event 1034, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0693 TimeModule> 1034 1 tpgcheck TPGCheck 0.0219259
0694 TimeModule> 1034 1 TriggerResults TriggerResultInserter 0.000279903
0695 TimeEvent> 1034 1 0.022954
0696 Begin processing the 35th record. Run 1, Event 1035, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0697 TimeModule> 1035 1 tpgcheck TPGCheck 0.0211518
0698 TimeModule> 1035 1 TriggerResults TriggerResultInserter 0.000247002
0699 TimeEvent> 1035 1 0.022027
0700 Begin processing the 36th record. Run 1, Event 1036, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0701 TimeModule> 1036 1 tpgcheck TPGCheck 0.0202451
0702 TimeModule> 1036 1 TriggerResults TriggerResultInserter 0.000386953
0703 TimeEvent> 1036 1 0.0212481
0704 Begin processing the 37th record. Run 1, Event 1037, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0705 TimeModule> 1037 1 tpgcheck TPGCheck 0.0205219
0706 TimeModule> 1037 1 TriggerResults TriggerResultInserter 0.000236034
0707 TimeEvent> 1037 1 0.0213761
0708 Begin processing the 38th record. Run 1, Event 1038, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0709 TimeModule> 1038 1 tpgcheck TPGCheck 0.020277
0710 TimeModule> 1038 1 TriggerResults TriggerResultInserter 0.000248194
0711 TimeEvent> 1038 1 0.021173
0712 Begin processing the 39th record. Run 1, Event 1039, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0713 TimeModule> 1039 1 tpgcheck TPGCheck 0.0201101
0714 TimeModule> 1039 1 TriggerResults TriggerResultInserter 0.000236034
0715 TimeEvent> 1039 1 0.0210059
0716 Begin processing the 40th record. Run 1, Event 1040, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0717 TimeModule> 1040 1 tpgcheck TPGCheck 0.0200012
0718 TimeModule> 1040 1 TriggerResults TriggerResultInserter 0.000241041
0719 TimeEvent> 1040 1 0.0208981
0720 Begin processing the 41st record. Run 1, Event 1041, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0721 TimeModule> 1041 1 tpgcheck TPGCheck 0.018836
0722 TimeModule> 1041 1 TriggerResults TriggerResultInserter 0.00023222
0723 TimeEvent> 1041 1 0.0196881
0724 Begin processing the 42nd record. Run 1, Event 1042, LumiSection 666670 at 05-Feb-2009 14:02:25 CET
0725 TimeModule> 1042 1 tpgcheck TPGCheck 0.01917
0726 TimeModule> 1042 1 TriggerResults TriggerResultInserter 0.000241995
0727 TimeEvent> 1042 1 0.0200381
0728 Begin processing the 43rd record. Run 1, Event 1043, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0729 TimeModule> 1043 1 tpgcheck TPGCheck 0.019892
0730 TimeModule> 1043 1 TriggerResults TriggerResultInserter 0.00037694
0731 TimeEvent> 1043 1 0.0208941
0732 Begin processing the 44th record. Run 1, Event 1044, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0733 TimeModule> 1044 1 tpgcheck TPGCheck 0.0194921
0734 TimeModule> 1044 1 TriggerResults TriggerResultInserter 0.000239849
0735 TimeEvent> 1044 1 0.020349
0736 Begin processing the 45th record. Run 1, Event 1045, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0737 TimeModule> 1045 1 tpgcheck TPGCheck 0.0197959
0738 TimeModule> 1045 1 TriggerResults TriggerResultInserter 0.000231981
0739 TimeEvent> 1045 1 0.020642
0740 Begin processing the 46th record. Run 1, Event 1046, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0741 TimeModule> 1046 1 tpgcheck TPGCheck 0.0205112
0742 TimeModule> 1046 1 TriggerResults TriggerResultInserter 0.000236034
0743 TimeEvent> 1046 1 0.021369
0744 Begin processing the 47th record. Run 1, Event 1047, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0745 TimeModule> 1047 1 tpgcheck TPGCheck 0.020555
0746 TimeModule> 1047 1 TriggerResults TriggerResultInserter 0.000236988
0747 TimeEvent> 1047 1 0.02141
0748 Begin processing the 48th record. Run 1, Event 1048, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0749 TimeModule> 1048 1 tpgcheck TPGCheck 0.0190029
0750 TimeModule> 1048 1 TriggerResults TriggerResultInserter 0.000238895
0751 TimeEvent> 1048 1 0.019875
0752 Begin processing the 49th record. Run 1, Event 1049, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0753 TimeModule> 1049 1 tpgcheck TPGCheck 0.0211771
0754 TimeModule> 1049 1 TriggerResults TriggerResultInserter 0.000242949
0755 TimeEvent> 1049 1 0.0220499
0756 Begin processing the 50th record. Run 1, Event 1050, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0757 TimeModule> 1050 1 tpgcheck TPGCheck 0.0192659
0758 TimeModule> 1050 1 TriggerResults TriggerResultInserter 0.000381947
0759 TimeEvent> 1050 1 0.020277
0760 Begin processing the 51st record. Run 1, Event 1051, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0761 TimeModule> 1051 1 tpgcheck TPGCheck 0.020884
0762 TimeModule> 1051 1 TriggerResults TriggerResultInserter 0.000240803
0763 TimeEvent> 1051 1 0.0217628
0764 Begin processing the 52nd record. Run 1, Event 1052, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0765 TimeModule> 1052 1 tpgcheck TPGCheck 0.0200861
0766 TimeModule> 1052 1 TriggerResults TriggerResultInserter 0.000241995
0767 TimeEvent> 1052 1 0.0209422
0768 Begin processing the 53rd record. Run 1, Event 1053, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0769 TimeModule> 1053 1 tpgcheck TPGCheck 0.0208409
0770 TimeModule> 1053 1 TriggerResults TriggerResultInserter 0.00026679
0771 TimeEvent> 1053 1 0.0218661
0772 Begin processing the 54th record. Run 1, Event 1054, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0773 TimeModule> 1054 1 tpgcheck TPGCheck 0.019336
0774 TimeModule> 1054 1 TriggerResults TriggerResultInserter 0.000250101
0775 TimeEvent> 1054 1 0.0202019
0776 Begin processing the 55th record. Run 1, Event 1055, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0777 TimeModule> 1055 1 tpgcheck TPGCheck 0.020184
0778 TimeModule> 1055 1 TriggerResults TriggerResultInserter 0.000249863
0779 TimeEvent> 1055 1 0.0210609
0780 Begin processing the 56th record. Run 1, Event 1056, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0781 TimeModule> 1056 1 tpgcheck TPGCheck 0.0198989
0782 TimeModule> 1056 1 TriggerResults TriggerResultInserter 0.000241995
0783 TimeEvent> 1056 1 0.0208189
0784 Begin processing the 57th record. Run 1, Event 1057, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0785 TimeModule> 1057 1 tpgcheck TPGCheck 0.0203459
0786 TimeModule> 1057 1 TriggerResults TriggerResultInserter 0.000398159
0787 TimeEvent> 1057 1 0.021415
0788 Begin processing the 58th record. Run 1, Event 1058, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0789 TimeModule> 1058 1 tpgcheck TPGCheck 0.0208502
0790 TimeModule> 1058 1 TriggerResults TriggerResultInserter 0.000235081
0791 TimeEvent> 1058 1 0.021703
0792 Begin processing the 59th record. Run 1, Event 1059, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0793 TimeModule> 1059 1 tpgcheck TPGCheck 0.021296
0794 TimeModule> 1059 1 TriggerResults TriggerResultInserter 0.000241041
0795 TimeEvent> 1059 1 0.0221591
0796 Begin processing the 60th record. Run 1, Event 1060, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0797 TimeModule> 1060 1 tpgcheck TPGCheck 0.0187602
0798 TimeModule> 1060 1 TriggerResults TriggerResultInserter 0.000241041
0799 TimeEvent> 1060 1 0.0196171
0800 Begin processing the 61st record. Run 1, Event 1061, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0801 TimeModule> 1061 1 tpgcheck TPGCheck 0.018292
0802 TimeModule> 1061 1 TriggerResults TriggerResultInserter 0.000241041
0803 TimeEvent> 1061 1 0.0191362
0804 Begin processing the 62nd record. Run 1, Event 1062, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0805 TimeModule> 1062 1 tpgcheck TPGCheck 0.0192099
0806 TimeModule> 1062 1 TriggerResults TriggerResultInserter 0.000242949
0807 TimeEvent> 1062 1 0.020061
0808 Begin processing the 63rd record. Run 1, Event 1063, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0809 TimeModule> 1063 1 tpgcheck TPGCheck 0.0196218
0810 TimeModule> 1063 1 TriggerResults TriggerResultInserter 0.00024581
0811 TimeEvent> 1063 1 0.02052
0812 Begin processing the 64th record. Run 1, Event 1064, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0813 TimeModule> 1064 1 tpgcheck TPGCheck 0.0199082
0814 TimeModule> 1064 1 TriggerResults TriggerResultInserter 0.000386
0815 TimeEvent> 1064 1 0.020926
0816 Begin processing the 65th record. Run 1, Event 1065, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0817 TimeModule> 1065 1 tpgcheck TPGCheck 0.0195599
0818 TimeModule> 1065 1 TriggerResults TriggerResultInserter 0.000234842
0819 TimeEvent> 1065 1 0.020416
0820 Begin processing the 66th record. Run 1, Event 1066, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0821 TimeModule> 1066 1 tpgcheck TPGCheck 0.020129
0822 TimeModule> 1066 1 TriggerResults TriggerResultInserter 0.000242949
0823 TimeEvent> 1066 1 0.020987
0824 Begin processing the 67th record. Run 1, Event 1067, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0825 TimeModule> 1067 1 tpgcheck TPGCheck 0.0193982
0826 TimeModule> 1067 1 TriggerResults TriggerResultInserter 0.000232935
0827 TimeEvent> 1067 1 0.0202332
0828 Begin processing the 68th record. Run 1, Event 1068, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0829 TimeModule> 1068 1 tpgcheck TPGCheck 0.0201721
0830 TimeModule> 1068 1 TriggerResults TriggerResultInserter 0.000236988
0831 TimeEvent> 1068 1 0.0210221
0832 Begin processing the 69th record. Run 1, Event 1069, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0833 TimeModule> 1069 1 tpgcheck TPGCheck 0.0188398
0834 TimeModule> 1069 1 TriggerResults TriggerResultInserter 0.000242949
0835 TimeEvent> 1069 1 0.0196931
0836 Begin processing the 70th record. Run 1, Event 1070, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0837 TimeModule> 1070 1 tpgcheck TPGCheck 0.0208189
0838 TimeModule> 1070 1 TriggerResults TriggerResultInserter 0.000234842
0839 TimeEvent> 1070 1 0.0216641
0840 Begin processing the 71st record. Run 1, Event 1071, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0841 TimeModule> 1071 1 tpgcheck TPGCheck 0.0195129
0842 TimeModule> 1071 1 TriggerResults TriggerResultInserter 0.000385046
0843 TimeEvent> 1071 1 0.0205112
0844 Begin processing the 72nd record. Run 1, Event 1072, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0845 TimeModule> 1072 1 tpgcheck TPGCheck 0.0195479
0846 TimeModule> 1072 1 TriggerResults TriggerResultInserter 0.000234127
0847 TimeEvent> 1072 1 0.0204101
0848 Begin processing the 73rd record. Run 1, Event 1073, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0849 TimeModule> 1073 1 tpgcheck TPGCheck 0.019285
0850 TimeModule> 1073 1 TriggerResults TriggerResultInserter 0.000238895
0851 TimeEvent> 1073 1 0.020185
0852 Begin processing the 74th record. Run 1, Event 1074, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0853 TimeModule> 1074 1 tpgcheck TPGCheck 0.0198939
0854 TimeModule> 1074 1 TriggerResults TriggerResultInserter 0.000247955
0855 TimeEvent> 1074 1 0.0207939
0856 Begin processing the 75th record. Run 1, Event 1075, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0857 TimeModule> 1075 1 tpgcheck TPGCheck 0.019419
0858 TimeModule> 1075 1 TriggerResults TriggerResultInserter 0.000234127
0859 TimeEvent> 1075 1 0.0202808
0860 Begin processing the 76th record. Run 1, Event 1076, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0861 TimeModule> 1076 1 tpgcheck TPGCheck 0.0201709
0862 TimeModule> 1076 1 TriggerResults TriggerResultInserter 0.000251055
0863 TimeEvent> 1076 1 0.0210378
0864 Begin processing the 77th record. Run 1, Event 1077, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0865 TimeModule> 1077 1 tpgcheck TPGCheck 0.021246
0866 TimeModule> 1077 1 TriggerResults TriggerResultInserter 0.000266075
0867 TimeEvent> 1077 1 0.0222142
0868 Begin processing the 78th record. Run 1, Event 1078, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0869 TimeModule> 1078 1 tpgcheck TPGCheck 0.0205162
0870 TimeModule> 1078 1 TriggerResults TriggerResultInserter 0.000377893
0871 TimeEvent> 1078 1 0.021508
0872 Begin processing the 79th record. Run 1, Event 1079, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0873 TimeModule> 1079 1 tpgcheck TPGCheck 0.019645
0874 TimeModule> 1079 1 TriggerResults TriggerResultInserter 0.000249147
0875 TimeEvent> 1079 1 0.020514
0876 Begin processing the 80th record. Run 1, Event 1080, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0877 TimeModule> 1080 1 tpgcheck TPGCheck 0.0193541
0878 TimeModule> 1080 1 TriggerResults TriggerResultInserter 0.000253916
0879 TimeEvent> 1080 1 0.0202549
0880 Begin processing the 81st record. Run 1, Event 1081, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0881 TimeModule> 1081 1 tpgcheck TPGCheck 0.0195329
0882 TimeModule> 1081 1 TriggerResults TriggerResultInserter 0.000235796
0883 TimeEvent> 1081 1 0.0203981
0884 Begin processing the 82nd record. Run 1, Event 1082, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0885 TimeModule> 1082 1 tpgcheck TPGCheck 0.0189159
0886 TimeModule> 1082 1 TriggerResults TriggerResultInserter 0.000246048
0887 TimeEvent> 1082 1 0.0197721
0888 Begin processing the 83rd record. Run 1, Event 1083, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0889 TimeModule> 1083 1 tpgcheck TPGCheck 0.020566
0890 TimeModule> 1083 1 TriggerResults TriggerResultInserter 0.000236988
0891 TimeEvent> 1083 1 0.0214372
0892 Begin processing the 84th record. Run 1, Event 1084, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0893 TimeModule> 1084 1 tpgcheck TPGCheck 0.0194821
0894 TimeModule> 1084 1 TriggerResults TriggerResultInserter 0.000239849
0895 TimeEvent> 1084 1 0.0203421
0896 Begin processing the 85th record. Run 1, Event 1085, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0897 TimeModule> 1085 1 tpgcheck TPGCheck 0.0203409
0898 TimeModule> 1085 1 TriggerResults TriggerResultInserter 0.000383854
0899 TimeEvent> 1085 1 0.0213571
0900 Begin processing the 86th record. Run 1, Event 1086, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0901 TimeModule> 1086 1 tpgcheck TPGCheck 0.0220029
0902 TimeModule> 1086 1 TriggerResults TriggerResultInserter 0.000239134
0903 TimeEvent> 1086 1 0.0228791
0904 Begin processing the 87th record. Run 1, Event 1087, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0905 TimeModule> 1087 1 tpgcheck TPGCheck 0.019269
0906 TimeModule> 1087 1 TriggerResults TriggerResultInserter 0.000236034
0907 TimeEvent> 1087 1 0.0201209
0908 Begin processing the 88th record. Run 1, Event 1088, LumiSection 666670 at 05-Feb-2009 14:02:26 CET
0909 TimeModule> 1088 1 tpgcheck TPGCheck 0.018605
0910 TimeModule> 1088 1 TriggerResults TriggerResultInserter 0.000248909
0911 TimeEvent> 1088 1 0.0195272
0912 Begin processing the 89th record. Run 1, Event 1089, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0913 TimeModule> 1089 1 tpgcheck TPGCheck 0.0203848
0914 TimeModule> 1089 1 TriggerResults TriggerResultInserter 0.000268936
0915 TimeEvent> 1089 1 0.0214081
0916 Begin processing the 90th record. Run 1, Event 1090, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0917 TimeModule> 1090 1 tpgcheck TPGCheck 0.020576
0918 TimeModule> 1090 1 TriggerResults TriggerResultInserter 0.000244856
0919 TimeEvent> 1090 1 0.0215068
0920 Begin processing the 91st record. Run 1, Event 1091, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0921 TimeModule> 1091 1 tpgcheck TPGCheck 0.0185969
0922 TimeModule> 1091 1 TriggerResults TriggerResultInserter 0.000235081
0923 TimeEvent> 1091 1 0.0194609
0924 Begin processing the 92nd record. Run 1, Event 1092, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0925 TimeModule> 1092 1 tpgcheck TPGCheck 0.0181952
0926 TimeModule> 1092 1 TriggerResults TriggerResultInserter 0.000370979
0927 TimeEvent> 1092 1 0.0191779
0928 Begin processing the 93rd record. Run 1, Event 1093, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0929 TimeModule> 1093 1 tpgcheck TPGCheck 0.019603
0930 TimeModule> 1093 1 TriggerResults TriggerResultInserter 0.000234127
0931 TimeEvent> 1093 1 0.0204461
0932 Begin processing the 94th record. Run 1, Event 1094, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0933 TimeModule> 1094 1 tpgcheck TPGCheck 0.019731
0934 TimeModule> 1094 1 TriggerResults TriggerResultInserter 0.000249147
0935 TimeEvent> 1094 1 0.0206001
0936 Begin processing the 95th record. Run 1, Event 1095, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0937 TimeModule> 1095 1 tpgcheck TPGCheck 0.020807
0938 TimeModule> 1095 1 TriggerResults TriggerResultInserter 0.000236034
0939 TimeEvent> 1095 1 0.0216551
0940 Begin processing the 96th record. Run 1, Event 1096, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0941 TimeModule> 1096 1 tpgcheck TPGCheck 0.0194042
0942 TimeModule> 1096 1 TriggerResults TriggerResultInserter 0.000238895
0943 TimeEvent> 1096 1 0.020267
0944 Begin processing the 97th record. Run 1, Event 1097, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0945 TimeModule> 1097 1 tpgcheck TPGCheck 0.020427
0946 TimeModule> 1097 1 TriggerResults TriggerResultInserter 0.000242949
0947 TimeEvent> 1097 1 0.021297
0948 Begin processing the 98th record. Run 1, Event 1098, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0949 TimeModule> 1098 1 tpgcheck TPGCheck 0.019897
0950 TimeModule> 1098 1 TriggerResults TriggerResultInserter 0.000236034
0951 TimeEvent> 1098 1 0.020752
0952 Begin processing the 99th record. Run 1, Event 1099, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0953 TimeModule> 1099 1 tpgcheck TPGCheck 0.0196021
0954 TimeModule> 1099 1 TriggerResults TriggerResultInserter 0.000250101
0955 TimeEvent> 1099 1 0.0204668
0956 Begin processing the 100th record. Run 1, Event 1100, LumiSection 666670 at 05-Feb-2009 14:02:27 CET
0957 TimeModule> 1100 1 tpgcheck TPGCheck 0.0199821
0958 TimeModule> 1100 1 TriggerResults TriggerResultInserter 0.000235081
0959 TimeEvent> 1100 1 0.020824
0960 05-Feb-2009 14:02:27 CET Closed file file:TrigPrim_suppressed.root
0961 TimeReport> Time report complete in 2.35953 seconds
0962 Time Summary:
0963 Min: 0.0191362
0964 Max: 0.0513909
0965 Avg: 0.0235953
0966
0967
0968 =============================================
0969
0970 MessageLogger Summary
0971
0972 type category sev module subroutine count total
0973 ---- -------------------- -- ---------------- ---------------- ----- -----
0974 1 TimeEvent -s PostModule 100 100
0975 2 TimeModule -s TPGCheck:tpgchec 100 100
0976 3 TimeModule -s TriggerResultIns 100 100
0977 4 TimeReport -s 1 1
0978 5 TimeReport -s PostModule 1 1
0979 6 fileAction -s PoolSource:sourc 2 2
0980 7 fileAction -s PostModule 1 1
0981
0982 type category Examples: run/evt run/evt run/evt
0983 ---- -------------------- ---------------- ---------------- ----------------
0984 1 TimeEvent 1/1001 1/1002 1/1100
0985 2 TimeModule 1/1001 1/1002 1/1100
0986 3 TimeModule 1/1001 1/1002 1/1100
0987 4 TimeReport BeforeEvents
0988 5 TimeReport 1/1100
0989 6 fileAction pre-events pre-events
0990 7 fileAction 1/1100
0991
0992 Severity # Occurrences Total Occurrences
0993 -------- ------------- -----------------
0994 System 305 305
0995 ===================> Step3: rereading histos with root
0996 *******************************************
0997 * *
0998 * W E L C O M E to R O O T *
0999 * *
1000 * Version 5.18/00a 20 February 2008 *
1001 * *
1002 * You are welcome to visit our Web site *
1003 * http://root.cern.ch *
1004 * *
1005 *******************************************
1006
1007 ROOT 5.18/00a (branches/v5-18-00-patches@22235, Jan 01 2009, 14:00:00 on linux)
1008
1009 CINT/ROOT C/C++ Interpreter version 5.16.29, Jan 08, 2008
1010 Type ? for help. Commands must be C++ statements.
1011 Enclose multiple statements between { }.
1012
1013 Processing HistoCompare.C...
1014
1015 [OVAL] Mean of Barrel energy histo 0.16496
1016 [OVAL] Mean of barrel fgvb histo 0
1017 [OVAL] Mean of barrel ttf histo 0.0140564
1018 [OVAL] Mean of Endcap energy histo 0.127382
1019 [OVAL] Mean of Endcap fgvb histo 0
1020 [OVAL] Mean of endcap ttf histo 0.0147495
1021
1022 [oval run] ==========================================
1023 [oval run] result: OK
1024 [oval run] ==========================================
1025
1026 [oval diff] ================================================
1027 [oval diff] diff line: /^\[OVAL\](.*)$/
1028 [oval diff] ================================================
1029
1030 [oval diff] =========================================
1031 [oval diff] result: OK
1032 [oval diff] =========================================
1033
