04-Feb-2009 17:17:13 CST  Initiating request to open file dcap://cmsdca.fnal.gov:24137/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/QCD_BCtoE_Pt30to80/GEN-SIM-RECO/IDEAL_V9_v1/0004/F22B4CE7-9688-DD11-BD55-00144F0D6BE4.root
04-Feb-2009 17:17:28 CST  Successfully opened file dcap://cmsdca.fnal.gov:24137/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/QCD_BCtoE_Pt30to80/GEN-SIM-RECO/IDEAL_V9_v1/0004/F22B4CE7-9688-DD11-BD55-00144F0D6BE4.root
Begin processing the 1st record. Run 1, Event 7227, LumiSection 15 at 04-Feb-2009 17:17:37 CST
Begin processing the 2nd record. Run 1, Event 8429, LumiSection 15 at 04-Feb-2009 17:17:38 CST
el->gsfTrack()->d0()=0.0183789
Begin processing the 3rd record. Run 1, Event 8594, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.00696698
Begin processing the 4th record. Run 1, Event 8958, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.0206463
Begin processing the 5th record. Run 1, Event 8982, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.0246289
Begin processing the 6th record. Run 1, Event 11463, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.0133813
Begin processing the 7th record. Run 1, Event 11647, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.0168917
Begin processing the 8th record. Run 1, Event 12924, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.282539
Begin processing the 9th record. Run 1, Event 13090, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.0142207
Begin processing the 10th record. Run 1, Event 13920, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.0248796
Begin processing the 11th record. Run 1, Event 14121, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.0683108
Begin processing the 12th record. Run 1, Event 14691, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.00979108
el->gsfTrack()->d0()=0.00868336
Begin processing the 13th record. Run 1, Event 14818, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.0253219
Begin processing the 14th record. Run 1, Event 15200, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.0193417
Begin processing the 15th record. Run 1, Event 15335, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.0239962
Begin processing the 16th record. Run 1, Event 15856, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.0108916
el->gsfTrack()->d0()=-0.0680561
Begin processing the 17th record. Run 1, Event 16092, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.0259719
el->gsfTrack()->d0()=-0.01952
Begin processing the 18th record. Run 1, Event 16172, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.0154249
Begin processing the 19th record. Run 1, Event 17361, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=-0.0116175
Begin processing the 20th record. Run 1, Event 17911, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.0427556
Begin processing the 21st record. Run 1, Event 18081, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.0308634
el->gsfTrack()->d0()=-0.0289969
Begin processing the 22nd record. Run 1, Event 19498, LumiSection 15 at 04-Feb-2009 17:17:39 CST
el->gsfTrack()->d0()=0.0202186
Begin processing the 23rd record. Run 1, Event 19570, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0349863
Begin processing the 24th record. Run 1, Event 19749, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.016172
Begin processing the 25th record. Run 1, Event 19813, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0204829
Begin processing the 26th record. Run 1, Event 21373, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.000111907
Begin processing the 27th record. Run 1, Event 22404, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.00248094
Begin processing the 28th record. Run 1, Event 22892, LumiSection 15 at 04-Feb-2009 17:17:40 CST
Begin processing the 29th record. Run 1, Event 24290, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0158535
Begin processing the 30th record. Run 1, Event 24574, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.00326744
Begin processing the 31st record. Run 1, Event 24775, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0330862
Begin processing the 32nd record. Run 1, Event 24978, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0287117
Begin processing the 33rd record. Run 1, Event 25640, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0122757
Begin processing the 34th record. Run 1, Event 25735, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0147411
Begin processing the 35th record. Run 1, Event 25927, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0325294
el->gsfTrack()->d0()=0.0464002
Begin processing the 36th record. Run 1, Event 26395, LumiSection 15 at 04-Feb-2009 17:17:40 CST
Begin processing the 37th record. Run 1, Event 26563, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.00428201
el->gsfTrack()->d0()=-0.013075
Begin processing the 38th record. Run 1, Event 27362, LumiSection 15 at 04-Feb-2009 17:17:40 CST
Begin processing the 39th record. Run 1, Event 28182, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.00855072
el->gsfTrack()->d0()=0.068137
Begin processing the 40th record. Run 1, Event 29200, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0230844
Begin processing the 41st record. Run 1, Event 29258, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0274339
Begin processing the 42nd record. Run 1, Event 29277, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.00237481
Begin processing the 43rd record. Run 1, Event 29754, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0402032
el->gsfTrack()->d0()=-0.0615043
Begin processing the 44th record. Run 1, Event 30597, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0195397
Begin processing the 45th record. Run 1, Event 30888, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.00937553
%MSG-w ScheduleExecutionFailure:  PostModule 04-Feb-2009 17:17:40 CST  Run: 1 Event: 30888
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: 30888
---- InvalidReference END
Exception going through path p
---- ScheduleExecutionFailure END

%MSG
Begin processing the 46th record. Run 1, Event 31170, LumiSection 15 at 04-Feb-2009 17:17:40 CST
Begin processing the 47th record. Run 1, Event 31492, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0186597
Begin processing the 48th record. Run 1, Event 31989, LumiSection 15 at 04-Feb-2009 17:17:40 CST
Begin processing the 49th record. Run 1, Event 32096, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0395566
Begin processing the 50th record. Run 1, Event 33003, LumiSection 15 at 04-Feb-2009 17:17:40 CST
Begin processing the 51st record. Run 1, Event 33442, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0420825
Begin processing the 52nd record. Run 1, Event 33623, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0471682
Begin processing the 53rd record. Run 1, Event 33872, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.00150975
el->gsfTrack()->d0()=-0.223968
Begin processing the 54th record. Run 1, Event 34746, LumiSection 15 at 04-Feb-2009 17:17:40 CST
Begin processing the 55th record. Run 1, Event 34834, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0225337
el->gsfTrack()->d0()=0.0179027
Begin processing the 56th record. Run 1, Event 35530, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0205669
Begin processing the 57th record. Run 1, Event 35780, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=0.0103584
Begin processing the 58th record. Run 1, Event 36433, LumiSection 15 at 04-Feb-2009 17:17:40 CST
Begin processing the 59th record. Run 1, Event 36443, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.00781673
el->gsfTrack()->d0()=0.0131322
Begin processing the 60th record. Run 1, Event 36484, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.0542899
Begin processing the 61st record. Run 1, Event 36678, LumiSection 15 at 04-Feb-2009 17:17:40 CST
el->gsfTrack()->d0()=-0.00408456
el->gsfTrack()->d0()=-0.00789853
Begin processing the 62nd record. Run 1, Event 37082, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0274639
Begin processing the 63rd record. Run 1, Event 37298, LumiSection 15 at 04-Feb-2009 17:17:41 CST
Begin processing the 64th record. Run 1, Event 37348, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0418238
Begin processing the 65th record. Run 1, Event 37429, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0248641
Begin processing the 66th record. Run 1, Event 38183, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0268772
Begin processing the 67th record. Run 1, Event 38517, LumiSection 15 at 04-Feb-2009 17:17:41 CST
Begin processing the 68th record. Run 1, Event 38589, LumiSection 15 at 04-Feb-2009 17:17:41 CST
Begin processing the 69th record. Run 1, Event 39141, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.033135
Begin processing the 70th record. Run 1, Event 39190, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0405656
el->gsfTrack()->d0()=0.063031
Begin processing the 71st record. Run 1, Event 39215, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0183231
Begin processing the 72nd record. Run 1, Event 39635, LumiSection 15 at 04-Feb-2009 17:17:41 CST
Begin processing the 73rd record. Run 1, Event 40151, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0394965
el->gsfTrack()->d0()=0.00700533
Begin processing the 74th record. Run 1, Event 40282, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0324613
Begin processing the 75th record. Run 1, Event 40725, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.00553474
Begin processing the 76th record. Run 1, Event 41127, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0119083
Begin processing the 77th record. Run 1, Event 41205, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0359803
Begin processing the 78th record. Run 1, Event 41346, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0164878
Begin processing the 79th record. Run 1, Event 41853, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0346105
el->gsfTrack()->d0()=0.0226377
el->gsfTrack()->d0()=-0.0296901
Begin processing the 80th record. Run 1, Event 42534, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.015197
Begin processing the 81st record. Run 1, Event 42589, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0503406
Begin processing the 82nd record. Run 1, Event 42958, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0169101
Begin processing the 83rd record. Run 1, Event 43331, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0118674
Begin processing the 84th record. Run 1, Event 44228, LumiSection 15 at 04-Feb-2009 17:17:41 CST
Begin processing the 85th record. Run 1, Event 44410, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0287271
el->gsfTrack()->d0()=-0.0287308
Begin processing the 86th record. Run 1, Event 44789, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0139362
el->gsfTrack()->d0()=-0.0218829
Begin processing the 87th record. Run 1, Event 45418, LumiSection 15 at 04-Feb-2009 17:17:41 CST
Begin processing the 88th record. Run 1, Event 46204, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0325224
Begin processing the 89th record. Run 1, Event 46218, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0380786
Begin processing the 90th record. Run 1, Event 46949, LumiSection 15 at 04-Feb-2009 17:17:41 CST
Begin processing the 91st record. Run 1, Event 47792, LumiSection 15 at 04-Feb-2009 17:17:41 CST
Begin processing the 92nd record. Run 1, Event 47906, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0331564
Begin processing the 93rd record. Run 1, Event 48172, LumiSection 15 at 04-Feb-2009 17:17:41 CST
%MSG-w ScheduleExecutionFailure:  PostModule 04-Feb-2009 17:17:41 CST  Run: 1 Event: 48172
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: 48172
---- InvalidReference END
Exception going through path p
---- ScheduleExecutionFailure END

%MSG
Begin processing the 94th record. Run 1, Event 48340, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0183045
Begin processing the 95th record. Run 1, Event 48614, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0252552
Begin processing the 96th record. Run 1, Event 48895, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=-0.0506427
Begin processing the 97th record. Run 1, Event 49033, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.00571038
el->gsfTrack()->d0()=0.00686604
Begin processing the 98th record. Run 1, Event 49293, LumiSection 15 at 04-Feb-2009 17:17:41 CST
Begin processing the 99th record. Run 1, Event 50187, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0168928
Begin processing the 100th record. Run 1, Event 50467, LumiSection 15 at 04-Feb-2009 17:17:41 CST
el->gsfTrack()->d0()=0.0384053
04-Feb-2009 17:17:41 CST  Closed file dcap://cmsdca.fnal.gov:24137/pnfs/fnal.gov/usr/cms/WAX/11/store/mc/Summer08/QCD_BCtoE_Pt30to80/GEN-SIM-RECO/IDEAL_V9_v1/0004/F22B4CE7-9688-DD11-BD55-00144F0D6BE4.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/30888          1/48172          
    2 fileAction           pre-events       pre-events       
    3 fileAction           1/50467                           

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