04-Feb-2009 17:12:47 CST  Initiating request to open file dcap://cmsdca3.fnal.gov:24143/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/QCD_EMenriched_Pt80to170/GEN-SIM-RECO/IDEAL_V9_v1/0051/68486682-D48A-DD11-80FE-00304833457A.root
04-Feb-2009 17:13:03 CST  Successfully opened file dcap://cmsdca3.fnal.gov:24143/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/QCD_EMenriched_Pt80to170/GEN-SIM-RECO/IDEAL_V9_v1/0051/68486682-D48A-DD11-80FE-00304833457A.root
Begin processing the 1st record. Run 1, Event 28213, LumiSection 95 at 04-Feb-2009 17:13:13 CST
Begin processing the 2nd record. Run 1, Event 28214, LumiSection 95 at 04-Feb-2009 17:13:13 CST
Begin processing the 3rd record. Run 1, Event 28218, LumiSection 95 at 04-Feb-2009 17:13:13 CST
Begin processing the 4th record. Run 1, Event 28237, LumiSection 95 at 04-Feb-2009 17:13:13 CST
el->gsfTrack()->d0()=-0.0294572
Begin processing the 5th record. Run 1, Event 28240, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 6th record. Run 1, Event 28247, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 7th record. Run 1, Event 28259, LumiSection 95 at 04-Feb-2009 17:13:15 CST
el->gsfTrack()->d0()=-0.000422065
Begin processing the 8th record. Run 1, Event 28263, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 9th record. Run 1, Event 28267, LumiSection 95 at 04-Feb-2009 17:13:15 CST
%MSG-w ScheduleExecutionFailure:  PostModule 04-Feb-2009 17:13:15 CST  Run: 1 Event: 28267
an exception occurred and all paths for the event are being skipped: 
---- ScheduleExecutionFailure BEGIN
ProcessingStopped
---- InvalidReference BEGIN
method "get" called with 0 arguments returned a null pointer cms::Exception going through module MuonSelector/goodMuonsforMETCorrection run: 1 event: 28267
---- InvalidReference END
Exception going through path p
---- ScheduleExecutionFailure END

%MSG
Begin processing the 10th record. Run 1, Event 28273, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 11th record. Run 1, Event 28274, LumiSection 95 at 04-Feb-2009 17:13:15 CST
el->gsfTrack()->d0()=0.0258449
el->gsfTrack()->d0()=-0.0217665
Begin processing the 12th record. Run 1, Event 28275, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 13th record. Run 1, Event 28279, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 14th record. Run 1, Event 28282, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 15th record. Run 1, Event 28291, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 16th record. Run 1, Event 28295, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 17th record. Run 1, Event 28300, LumiSection 95 at 04-Feb-2009 17:13:15 CST
el->gsfTrack()->d0()=-0.0278302
Begin processing the 18th record. Run 1, Event 28301, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 19th record. Run 1, Event 28312, LumiSection 95 at 04-Feb-2009 17:13:15 CST
Begin processing the 20th record. Run 1, Event 28321, LumiSection 95 at 04-Feb-2009 17:13:15 CST
el->gsfTrack()->d0()=0.0308471
Begin processing the 21st record. Run 1, Event 28322, LumiSection 95 at 04-Feb-2009 17:13:15 CST
el->gsfTrack()->d0()=-0.00634917
Begin processing the 22nd record. Run 1, Event 28328, LumiSection 95 at 04-Feb-2009 17:13:15 CST
el->gsfTrack()->d0()=0.0303077
el->gsfTrack()->d0()=0.0535541
Begin processing the 23rd record. Run 1, Event 28332, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.025034
Begin processing the 24th record. Run 1, Event 28341, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 25th record. Run 1, Event 28354, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 26th record. Run 1, Event 28357, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 27th record. Run 1, Event 28361, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=0.0306769
el->gsfTrack()->d0()=-0.0374996
Begin processing the 28th record. Run 1, Event 28370, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 29th record. Run 1, Event 28374, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=0.017978
Begin processing the 30th record. Run 1, Event 28380, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.0354095
el->gsfTrack()->d0()=-0.0103
el->gsfTrack()->d0()=-0.0383107
Begin processing the 31st record. Run 1, Event 28381, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 32nd record. Run 1, Event 28383, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 33rd record. Run 1, Event 28385, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 34th record. Run 1, Event 28387, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 35th record. Run 1, Event 28398, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 36th record. Run 1, Event 28400, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=0.00545872
Begin processing the 37th record. Run 1, Event 28403, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 38th record. Run 1, Event 28416, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=0.0345669
Begin processing the 39th record. Run 1, Event 28417, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 40th record. Run 1, Event 28423, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 41st record. Run 1, Event 28424, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.0253239
Begin processing the 42nd record. Run 1, Event 28425, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 43rd record. Run 1, Event 28426, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.0338216
Begin processing the 44th record. Run 1, Event 28434, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 45th record. Run 1, Event 28440, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 46th record. Run 1, Event 28446, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.0362927
el->gsfTrack()->d0()=-0.0341624
Begin processing the 47th record. Run 1, Event 28452, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 48th record. Run 1, Event 28453, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.0338684
el->gsfTrack()->d0()=0.0280204
Begin processing the 49th record. Run 1, Event 28462, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 50th record. Run 1, Event 28463, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 51st record. Run 1, Event 28468, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=0.0412049
Begin processing the 52nd record. Run 1, Event 28476, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 53rd record. Run 1, Event 28479, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.0160811
el->gsfTrack()->d0()=-0.0284072
Begin processing the 54th record. Run 1, Event 28481, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=0.00340548
Begin processing the 55th record. Run 1, Event 28500, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.0227793
Begin processing the 56th record. Run 1, Event 28516, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 57th record. Run 1, Event 28526, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=0.00275671
Begin processing the 58th record. Run 1, Event 28552, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 59th record. Run 1, Event 28554, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 60th record. Run 1, Event 28556, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=0.186969
Begin processing the 61st record. Run 1, Event 28561, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 62nd record. Run 1, Event 28564, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 63rd record. Run 1, Event 28566, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 64th record. Run 1, Event 28569, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 65th record. Run 1, Event 28571, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 66th record. Run 1, Event 28580, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.0213037
Begin processing the 67th record. Run 1, Event 28598, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.00710764
Begin processing the 68th record. Run 1, Event 28609, LumiSection 95 at 04-Feb-2009 17:13:16 CST
Begin processing the 69th record. Run 1, Event 28615, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.025164
Begin processing the 70th record. Run 1, Event 28621, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=0.00499972
Begin processing the 71st record. Run 1, Event 28628, LumiSection 95 at 04-Feb-2009 17:13:16 CST
el->gsfTrack()->d0()=-0.022992
el->gsfTrack()->d0()=0.00211777
Begin processing the 72nd record. Run 1, Event 28629, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=0.000999086
Begin processing the 73rd record. Run 1, Event 28637, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 74th record. Run 1, Event 28643, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=0.00138698
Begin processing the 75th record. Run 1, Event 28645, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=-0.00767894
Begin processing the 76th record. Run 1, Event 28660, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 77th record. Run 1, Event 28663, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=-0.0369309
Begin processing the 78th record. Run 1, Event 28672, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=0.0316008
Begin processing the 79th record. Run 1, Event 28680, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 80th record. Run 1, Event 28688, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=-0.0198623
Begin processing the 81st record. Run 1, Event 28689, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 82nd record. Run 1, Event 28691, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 83rd record. Run 1, Event 28700, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=0.497818
Begin processing the 84th record. Run 1, Event 28706, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 85th record. Run 1, Event 28709, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=0.00987403
Begin processing the 86th record. Run 1, Event 28711, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=-0.00935197
el->gsfTrack()->d0()=-0.0116121
el->gsfTrack()->d0()=-0.0432332
Begin processing the 87th record. Run 1, Event 28714, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=0.0348535
el->gsfTrack()->d0()=0.0230694
el->gsfTrack()->d0()=-0.0255604
Begin processing the 88th record. Run 1, Event 28722, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=0.0298351
Begin processing the 89th record. Run 1, Event 28724, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 90th record. Run 1, Event 28726, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=-0.0202085
el->gsfTrack()->d0()=0.0070031
el->gsfTrack()->d0()=-0.0142459
Begin processing the 91st record. Run 1, Event 28730, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=-0.0379939
Begin processing the 92nd record. Run 1, Event 28734, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 93rd record. Run 1, Event 28749, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=-0.0156144
el->gsfTrack()->d0()=0.0350296
Begin processing the 94th record. Run 1, Event 28751, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 95th record. Run 1, Event 28754, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 96th record. Run 1, Event 28755, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=0.0596388
Begin processing the 97th record. Run 1, Event 28767, LumiSection 95 at 04-Feb-2009 17:13:17 CST
Begin processing the 98th record. Run 1, Event 28768, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=-0.0290628
el->gsfTrack()->d0()=0.0239102
Begin processing the 99th record. Run 1, Event 28788, LumiSection 95 at 04-Feb-2009 17:13:17 CST
el->gsfTrack()->d0()=0.0153237
Begin processing the 100th record. Run 1, Event 28796, LumiSection 95 at 04-Feb-2009 17:13:17 CST
04-Feb-2009 17:13:17 CST  Closed file dcap://cmsdca3.fnal.gov:24143/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/QCD_EMenriched_Pt80to170/GEN-SIM-RECO/IDEAL_V9_v1/0051/68486682-D48A-DD11-80FE-00304833457A.root

=============================================

MessageLogger Summary

 type     category        sev    module        subroutine        count    total
 ---- -------------------- -- ---------------- ----------------  -----    -----
    1 ScheduleExecutionFai -w PostModule                             1        1
    2 fileAction           -s PoolSource:sourc                       2        2
    3 fileAction           -s PostModule                             1        1

 type    category    Examples: run/evt        run/evt          run/evt
 ---- -------------------- ---------------- ---------------- ----------------
    1 ScheduleExecutionFailure 1/28267                           
    2 fileAction           pre-events       pre-events       
    3 fileAction           1/28796                           

Severity    # Occurrences   Total Occurrences
--------    -------------   -----------------
Warning                 1                   1
System                  3                   3