04-Feb-2009 17:57:19 CST  Initiating request to open file dcap://cmsdca.fnal.gov:24136/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/InclusiveMu5Pt50/GEN-SIM-RECO/IDEAL_V9_v1/0001/A82A9EA2-7A92-DD11-8F96-00163691DE22.root
04-Feb-2009 17:57:33 CST  Successfully opened file dcap://cmsdca.fnal.gov:24136/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/InclusiveMu5Pt50/GEN-SIM-RECO/IDEAL_V9_v1/0001/A82A9EA2-7A92-DD11-8F96-00163691DE22.root
Begin processing the 1st record. Run 1, Event 1367554, LumiSection 2736 at 04-Feb-2009 17:57:45 CST
Begin processing the 2nd record. Run 1, Event 1367562, LumiSection 2736 at 04-Feb-2009 17:57:47 CST
Begin processing the 3rd record. Run 1, Event 1367607, LumiSection 2736 at 04-Feb-2009 17:57:47 CST
Begin processing the 4th record. Run 1, Event 1367613, LumiSection 2736 at 04-Feb-2009 17:57:47 CST
Begin processing the 5th record. Run 1, Event 1367723, LumiSection 2736 at 04-Feb-2009 17:57:47 CST
Begin processing the 6th record. Run 1, Event 1367774, LumiSection 2736 at 04-Feb-2009 17:57:47 CST
el->gsfTrack()->d0()=-1.49167e-154
el->gsfTrack()->d0()=1.41492
el->gsfTrack()->d0()=1.58764e-314
Begin processing the 7th record. Run 1, Event 1367813, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
el->gsfTrack()->d0()=-1.49167e-154
el->gsfTrack()->d0()=2.82843
Begin processing the 8th record. Run 1, Event 1367893, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
Begin processing the 9th record. Run 1, Event 1367895, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
el->gsfTrack()->d0()=-1.90026e+154
Begin processing the 10th record. Run 1, Event 1367901, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
Begin processing the 11th record. Run 1, Event 1367927, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
el->gsfTrack()->d0()=1.49167e-154
Begin processing the 12th record. Run 1, Event 1367938, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
el->gsfTrack()->d0()=-2.10954e-154
el->gsfTrack()->d0()=-7.12488e-161
Begin processing the 13th record. Run 1, Event 1367998, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
el->gsfTrack()->d0()=-2
Begin processing the 14th record. Run 1, Event 1368030, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
el->gsfTrack()->d0()=-9.21942e-10
Begin processing the 15th record. Run 1, Event 1368160, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
el->gsfTrack()->d0()=2
Begin processing the 16th record. Run 1, Event 1368167, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
Begin processing the 17th record. Run 1, Event 1368267, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
el->gsfTrack()->d0()=1.41421
Begin processing the 18th record. Run 1, Event 1368273, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
Begin processing the 19th record. Run 1, Event 1368294, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
Begin processing the 20th record. Run 1, Event 1368303, LumiSection 2736 at 04-Feb-2009 17:57:48 CST
el->gsfTrack()->d0()=-1.49167e-154
el->gsfTrack()->d0()=5.25566e-315
Begin processing the 21st record. Run 1, Event 1368363, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 22nd record. Run 1, Event 1368407, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=inf
Begin processing the 23rd record. Run 1, Event 1368475, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=1.05477e-154
Begin processing the 24th record. Run 1, Event 1368550, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 25th record. Run 1, Event 1368599, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=2
Begin processing the 26th record. Run 1, Event 1368624, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 27th record. Run 1, Event 1368669, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=-5.27064e-315
Begin processing the 28th record. Run 1, Event 1368741, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=1.49167e-154
Begin processing the 29th record. Run 1, Event 1368802, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=1.11254e-308
Begin processing the 30th record. Run 1, Event 1368814, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=-2.82843
Begin processing the 31st record. Run 1, Event 1368827, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=2.10954e-154
Begin processing the 32nd record. Run 1, Event 1368909, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 33rd record. Run 1, Event 1368912, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 34th record. Run 1, Event 1368935, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 35th record. Run 1, Event 1368969, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=2.68156e+154
Begin processing the 36th record. Run 1, Event 1369011, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 37th record. Run 1, Event 1369187, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 38th record. Run 1, Event 1369197, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 39th record. Run 1, Event 1369213, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=-2
Begin processing the 40th record. Run 1, Event 1369214, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 41st record. Run 1, Event 1369242, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=-2.68156e+154
Begin processing the 42nd record. Run 1, Event 1369277, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 43rd record. Run 1, Event 1369310, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=2
Begin processing the 44th record. Run 1, Event 1369324, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=inf
Begin processing the 45th record. Run 1, Event 1369346, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
Begin processing the 46th record. Run 1, Event 1369404, LumiSection 2736 at 04-Feb-2009 17:57:49 CST
el->gsfTrack()->d0()=-9.54749e-07
Begin processing the 47th record. Run 1, Event 1369432, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 48th record. Run 1, Event 1369441, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=2.68156e+154
el->gsfTrack()->d0()=2
Begin processing the 49th record. Run 1, Event 1369509, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 50th record. Run 1, Event 1369561, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 51st record. Run 1, Event 1369603, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 52nd record. Run 1, Event 1369630, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 53rd record. Run 1, Event 1369659, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=0
Begin processing the 54th record. Run 1, Event 1369668, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 55th record. Run 1, Event 1369712, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=-1.41421
el->gsfTrack()->d0()=1.49167e-154
el->gsfTrack()->d0()=-7.44733e-315
el->gsfTrack()->d0()=-1.05679e-314
Begin processing the 56th record. Run 1, Event 1369718, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=2
Begin processing the 57th record. Run 1, Event 1369728, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 58th record. Run 1, Event 1369815, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 59th record. Run 1, Event 1369873, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
%MSG-w ScheduleExecutionFailure:  PostModule 04-Feb-2009 17:57:50 CST  Run: 1 Event: 1369873
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: 1369873
---- InvalidReference END
Exception going through path p
---- ScheduleExecutionFailure END

%MSG
Begin processing the 60th record. Run 1, Event 1369884, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=7.47656e-315
el->gsfTrack()->d0()=1.89656e+154
Begin processing the 61st record. Run 1, Event 1369904, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=-1.89615e+154
Begin processing the 62nd record. Run 1, Event 1369934, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=-inf
Begin processing the 63rd record. Run 1, Event 1369935, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=2.68156e+154
Begin processing the 64th record. Run 1, Event 1369961, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 65th record. Run 1, Event 1370040, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=2
el->gsfTrack()->d0()=8.48951e+153
Begin processing the 66th record. Run 1, Event 1370088, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=8.48459e+153
el->gsfTrack()->d0()=-0.633722
Begin processing the 67th record. Run 1, Event 1370137, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=-5.27316e-315
Begin processing the 68th record. Run 1, Event 1370219, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 69th record. Run 1, Event 1370243, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=2
el->gsfTrack()->d0()=1.89615e+154
Begin processing the 70th record. Run 1, Event 1370251, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 71st record. Run 1, Event 1370290, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 72nd record. Run 1, Event 1370322, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 73rd record. Run 1, Event 1370404, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=1.49167e-154
Begin processing the 74th record. Run 1, Event 1370435, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 75th record. Run 1, Event 1370453, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 76th record. Run 1, Event 1370622, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 77th record. Run 1, Event 1370647, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 78th record. Run 1, Event 1370706, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=inf
el->gsfTrack()->d0()=-2.68156e+154
Begin processing the 79th record. Run 1, Event 1370764, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 80th record. Run 1, Event 1370808, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
Begin processing the 81st record. Run 1, Event 1370820, LumiSection 2736 at 04-Feb-2009 17:57:50 CST
el->gsfTrack()->d0()=-2.86342e-06
Begin processing the 82nd record. Run 1, Event 1370832, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 83rd record. Run 1, Event 1370843, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
el->gsfTrack()->d0()=1.49167e-154
Begin processing the 84th record. Run 1, Event 1370868, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 85th record. Run 1, Event 1370921, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 86th record. Run 1, Event 1370963, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
el->gsfTrack()->d0()=-2.68156e+154
Begin processing the 87th record. Run 1, Event 1370982, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 88th record. Run 1, Event 1370989, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 89th record. Run 1, Event 1371027, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 90th record. Run 1, Event 1371043, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 91st record. Run 1, Event 1371164, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
el->gsfTrack()->d0()=inf
Begin processing the 92nd record. Run 1, Event 1371194, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 93rd record. Run 1, Event 1371237, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
el->gsfTrack()->d0()=1.69453e+154
Begin processing the 94th record. Run 1, Event 1371301, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 95th record. Run 1, Event 1371307, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 96th record. Run 1, Event 1371328, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 97th record. Run 1, Event 1371351, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 98th record. Run 1, Event 1371365, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 99th record. Run 1, Event 1371440, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
Begin processing the 100th record. Run 1, Event 1371521, LumiSection 2736 at 04-Feb-2009 17:57:51 CST
el->gsfTrack()->d0()=-1.48261e-317
04-Feb-2009 17:57:51 CST  Closed file dcap://cmsdca.fnal.gov:24136/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/InclusiveMu5Pt50/GEN-SIM-RECO/IDEAL_V9_v1/0001/A82A9EA2-7A92-DD11-8F96-00163691DE22.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/1369873                         
    2 fileAction           pre-events       pre-events       
    3 fileAction           1/1371521                         

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