04-Feb-2009 18:01:48 CST  Initiating request to open file dcap://cmsdca3.fnal.gov:24143/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/InclusiveMuPt15/GEN-SIM-RECO/IDEAL_V9_v1/0003/CCCF2F1A-34AC-DD11-8318-003048770DCA.root
04-Feb-2009 18:02:08 CST  Successfully opened file dcap://cmsdca3.fnal.gov:24143/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/InclusiveMuPt15/GEN-SIM-RECO/IDEAL_V9_v1/0003/CCCF2F1A-34AC-DD11-8318-003048770DCA.root
Begin processing the 1st record. Run 1, Event 1599130, LumiSection 7387 at 04-Feb-2009 18:02:25 CST
el->gsfTrack()->d0()=-0.0103103
Begin processing the 2nd record. Run 1, Event 1603449, LumiSection 7387 at 04-Feb-2009 18:02:37 CST
Begin processing the 3rd record. Run 1, Event 1606547, LumiSection 7387 at 04-Feb-2009 18:02:37 CST
Begin processing the 4th record. Run 1, Event 1606843, LumiSection 7387 at 04-Feb-2009 18:02:38 CST
Begin processing the 5th record. Run 1, Event 1608469, LumiSection 7387 at 04-Feb-2009 18:02:38 CST
el->gsfTrack()->d0()=-0.028404
Begin processing the 6th record. Run 1, Event 1611386, LumiSection 7387 at 04-Feb-2009 18:02:38 CST
el->gsfTrack()->d0()=0.00672004
Begin processing the 7th record. Run 1, Event 1611935, LumiSection 7387 at 04-Feb-2009 18:02:38 CST
Begin processing the 8th record. Run 1, Event 1624561, LumiSection 7387 at 04-Feb-2009 18:02:38 CST
el->gsfTrack()->d0()=0.030898
el->gsfTrack()->d0()=-0.0609653
Begin processing the 9th record. Run 1, Event 1628972, LumiSection 7387 at 04-Feb-2009 18:02:38 CST
Begin processing the 10th record. Run 1, Event 1638387, LumiSection 7387 at 04-Feb-2009 18:02:38 CST
Begin processing the 11th record. Run 1, Event 1641003, LumiSection 7387 at 04-Feb-2009 18:02:38 CST
Begin processing the 12th record. Run 1, Event 1642765, LumiSection 7387 at 04-Feb-2009 18:02:39 CST
Begin processing the 13th record. Run 1, Event 1645591, LumiSection 7387 at 04-Feb-2009 18:02:39 CST
Begin processing the 14th record. Run 1, Event 1651922, LumiSection 7387 at 04-Feb-2009 18:02:39 CST
Begin processing the 15th record. Run 1, Event 1655227, LumiSection 7387 at 04-Feb-2009 18:02:39 CST
Begin processing the 16th record. Run 1, Event 1657100, LumiSection 7387 at 04-Feb-2009 18:02:39 CST
Begin processing the 17th record. Run 1, Event 1657832, LumiSection 7387 at 04-Feb-2009 18:02:39 CST
Begin processing the 18th record. Run 1, Event 1662296, LumiSection 7387 at 04-Feb-2009 18:02:39 CST
el->gsfTrack()->d0()=-0.0394035
Begin processing the 19th record. Run 1, Event 1663978, LumiSection 7387 at 04-Feb-2009 18:02:39 CST
Begin processing the 20th record. Run 1, Event 1670609, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 21st record. Run 1, Event 1672083, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 22nd record. Run 1, Event 1673748, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 23rd record. Run 1, Event 1677422, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 24th record. Run 1, Event 1683492, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 25th record. Run 1, Event 1688841, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 26th record. Run 1, Event 1693252, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 27th record. Run 1, Event 1700208, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 28th record. Run 1, Event 1703875, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 29th record. Run 1, Event 1705555, LumiSection 7387 at 04-Feb-2009 18:02:40 CST
Begin processing the 30th record. Run 1, Event 1708927, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 31st record. Run 1, Event 1716667, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 32nd record. Run 1, Event 1719597, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 33rd record. Run 1, Event 1719925, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 34th record. Run 1, Event 1726453, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 35th record. Run 1, Event 1727473, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 36th record. Run 1, Event 1734160, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 37th record. Run 1, Event 1738911, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 38th record. Run 1, Event 1739510, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
el->gsfTrack()->d0()=-0.00629156
Begin processing the 39th record. Run 1, Event 1740652, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
el->gsfTrack()->d0()=-0.0316987
Begin processing the 40th record. Run 1, Event 1741290, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 41st record. Run 1, Event 1748778, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 42nd record. Run 1, Event 1749148, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 43rd record. Run 1, Event 1750490, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
Begin processing the 44th record. Run 1, Event 1752449, LumiSection 7387 at 04-Feb-2009 18:02:41 CST
el->gsfTrack()->d0()=-0.0574977
Begin processing the 45th record. Run 1, Event 1755326, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
Begin processing the 46th record. Run 1, Event 1757492, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
el->gsfTrack()->d0()=0.0304276
Begin processing the 47th record. Run 1, Event 1765958, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
el->gsfTrack()->d0()=-0.0155664
Begin processing the 48th record. Run 1, Event 1769399, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
Begin processing the 49th record. Run 1, Event 1770758, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
el->gsfTrack()->d0()=-0.0121048
Begin processing the 50th record. Run 1, Event 1776963, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
Begin processing the 51st record. Run 1, Event 1777059, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
el->gsfTrack()->d0()=-0.0142433
Begin processing the 52nd record. Run 1, Event 1785871, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
Begin processing the 53rd record. Run 1, Event 1786798, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
el->gsfTrack()->d0()=-0.00210547
Begin processing the 54th record. Run 1, Event 1788414, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
Begin processing the 55th record. Run 1, Event 1792535, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
Begin processing the 56th record. Run 1, Event 1793277, LumiSection 7387 at 04-Feb-2009 18:02:42 CST
Begin processing the 57th record. Run 1, Event 1793693, LumiSection 7387 at 04-Feb-2009 18:02:43 CST
Begin processing the 58th record. Run 1, Event 1793963, LumiSection 7387 at 04-Feb-2009 18:02:43 CST
Begin processing the 59th record. Run 1, Event 1800055, LumiSection 7387 at 04-Feb-2009 18:02:43 CST
el->gsfTrack()->d0()=0.0309421
el->gsfTrack()->d0()=-0.00171418
Begin processing the 60th record. Run 1, Event 1800741, LumiSection 7387 at 04-Feb-2009 18:02:43 CST
Begin processing the 61st record. Run 1, Event 1801852, LumiSection 7387 at 04-Feb-2009 18:02:43 CST
Begin processing the 62nd record. Run 1, Event 1801987, LumiSection 7387 at 04-Feb-2009 18:02:44 CST
el->gsfTrack()->d0()=-0.0226905
Begin processing the 63rd record. Run 1, Event 1805812, LumiSection 7387 at 04-Feb-2009 18:02:44 CST
Begin processing the 64th record. Run 1, Event 1806810, LumiSection 7387 at 04-Feb-2009 18:02:44 CST
Begin processing the 65th record. Run 1, Event 1808303, LumiSection 7387 at 04-Feb-2009 18:02:44 CST
Begin processing the 66th record. Run 1, Event 1809788, LumiSection 7387 at 04-Feb-2009 18:02:44 CST
Begin processing the 67th record. Run 1, Event 1814342, LumiSection 7387 at 04-Feb-2009 18:02:44 CST
el->gsfTrack()->d0()=-0.00406496
Begin processing the 68th record. Run 1, Event 1816246, LumiSection 7387 at 04-Feb-2009 18:02:44 CST
Begin processing the 69th record. Run 1, Event 1817233, LumiSection 7387 at 04-Feb-2009 18:02:44 CST
Begin processing the 70th record. Run 1, Event 1819045, LumiSection 7387 at 04-Feb-2009 18:02:45 CST
Begin processing the 71st record. Run 1, Event 1819943, LumiSection 7387 at 04-Feb-2009 18:02:45 CST
Begin processing the 72nd record. Run 1, Event 1822026, LumiSection 7387 at 04-Feb-2009 18:02:45 CST
el->gsfTrack()->d0()=-0.0325623
Begin processing the 73rd record. Run 1, Event 1824731, LumiSection 7387 at 04-Feb-2009 18:02:45 CST
el->gsfTrack()->d0()=0.0133193
Begin processing the 74th record. Run 1, Event 1828999, LumiSection 7387 at 04-Feb-2009 18:02:45 CST
el->gsfTrack()->d0()=0.0277871
Begin processing the 75th record. Run 1, Event 1829980, LumiSection 7387 at 04-Feb-2009 18:02:45 CST
Begin processing the 76th record. Run 1, Event 1831076, LumiSection 7387 at 04-Feb-2009 18:02:45 CST
el->gsfTrack()->d0()=-0.00721886
Begin processing the 77th record. Run 1, Event 1833743, LumiSection 7387 at 04-Feb-2009 18:02:45 CST
Begin processing the 78th record. Run 1, Event 1833929, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
Begin processing the 79th record. Run 1, Event 1836343, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
Begin processing the 80th record. Run 1, Event 1841171, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
el->gsfTrack()->d0()=0.0312119
Begin processing the 81st record. Run 1, Event 1841584, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
el->gsfTrack()->d0()=0.0152273
Begin processing the 82nd record. Run 1, Event 1843051, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
Begin processing the 83rd record. Run 1, Event 1850235, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
Begin processing the 84th record. Run 1, Event 1850440, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
%MSG-w ScheduleExecutionFailure:  PostModule 04-Feb-2009 18:02:46 CST  Run: 1 Event: 1850440
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: 1850440
---- InvalidReference END
Exception going through path p
---- ScheduleExecutionFailure END

%MSG
Begin processing the 85th record. Run 1, Event 1864775, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
Begin processing the 86th record. Run 1, Event 1870193, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
Begin processing the 87th record. Run 1, Event 1876095, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
Begin processing the 88th record. Run 1, Event 1879659, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
Begin processing the 89th record. Run 1, Event 1882085, LumiSection 7387 at 04-Feb-2009 18:02:46 CST
Begin processing the 90th record. Run 1, Event 1882982, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
Begin processing the 91st record. Run 1, Event 1884320, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
%MSG-w ScheduleExecutionFailure:  PostModule 04-Feb-2009 18:02:47 CST  Run: 1 Event: 1884320
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: 1884320
---- InvalidReference END
Exception going through path p
---- ScheduleExecutionFailure END

%MSG
Begin processing the 92nd record. Run 1, Event 1887291, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
Begin processing the 93rd record. Run 1, Event 1888064, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
Begin processing the 94th record. Run 1, Event 1889258, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
Begin processing the 95th record. Run 1, Event 1891646, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
el->gsfTrack()->d0()=0.0334278
Begin processing the 96th record. Run 1, Event 1892478, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
Begin processing the 97th record. Run 1, Event 1892720, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
Begin processing the 98th record. Run 1, Event 1895261, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
Begin processing the 99th record. Run 1, Event 1903849, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
Begin processing the 100th record. Run 1, Event 1904611, LumiSection 7387 at 04-Feb-2009 18:02:47 CST
04-Feb-2009 18:02:48 CST  Closed file dcap://cmsdca3.fnal.gov:24143/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/InclusiveMuPt15/GEN-SIM-RECO/IDEAL_V9_v1/0003/CCCF2F1A-34AC-DD11-8318-003048770DCA.root

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

MessageLogger Summary

 type     category        sev    module        subroutine        count    total
 ---- -------------------- -- ---------------- ----------------  -----    -----
    1 ScheduleExecutionFai -w PostModule                             2        2
    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/1850440        1/1884320        
    2 fileAction           pre-events       pre-events       
    3 fileAction           1/1904611                         

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