Jobsub ID 41739.74@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
] frame sink sees EOS
[09:23:44.822] D [ pgraph ] <Pgrapher:> graph execution complete
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.78 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.66 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.06 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.72 sec
[09:23:44.822] I [ timer ] Timer: WireCell::Aux::Resampler : 0.52 sec
[09:23:44.822] I [ timer ] Timer: WireCell::Aux::Resampler : 0.5 sec
[09:23:44.822] I [ timer ] Timer: WireCell::Aux::Resampler : 0.48 sec
[09:23:44.822] I [ timer ] Timer: WireCell::Aux::Resampler : 0.47 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[09:23:44.822] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:23:44.822] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:23:44.822] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:23:44.822] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[09:23:44.823] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[09:23:44.823] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[09:23:44.823] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:23:44.823] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[09:23:44.823] I [ timer ] Timer: Total node execution : 38.229999758303165 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 19207 traces tagged "gauss"
FrameSaver: q=3.24769e+06 n=422275 tag=gauss
wclsFrameSaver: saving 23538 traces tagged "wiener"
FrameSaver: q=3.45644e+06 n=408069 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 843 XUs and 1343 XVs -> 41 XUVs
C:0 T:1 954 XUs and 1077 XVs -> 43 XUVs
C:0 T:2 346 XUs and 763 XVs -> 23 XUVs
C:0 T:3 6775 XUs and 8444 XVs -> 626 XUVs
C:0 T:4 856 XUs and 1030 XVs -> 31 XUVs
C:0 T:5 670 XUs and 910 XVs -> 32 XUVs
C:0 T:6 503 XUs and 601 XVs -> 19 XUVs
C:0 T:7 596 XUs and 669 XVs -> 26 XUVs
841 XUVs total
601 collection wire objects
841 potential space points
Neighbour search...
29275 tests to find 6718 neighbours
Iterating with no regularization...
Begin: 1.30721e+09
0 1.23264e+09
1 1.23158e+09
Now with regularization...
Begin: 1.21183e+09
0 1.2118e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 09:24:11 UTC Closed output file "np02vd_raw_run039433_0385_df-s05-d4_dw_0_20250918T070835_reco_stage1_20250918T092411_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 52.3146 98.3578 489.64 74.6636 81.4458 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 0.000105318 0.000236658 0.00179153 0.000176381 0.000258111 49
produce:tpcrawdecoder:PDVDTPCReader 7.32654 8.30196 12.2881 8.0578 0.895043 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.3956 0.401832 0.419358 0.400273 0.00508169 49
produce:pdvddaphne:DAPHNEReaderPDVD 3.38703 4.15777 5.66923 4.09049 0.48209 49
produce:ophit:OpHitFinder 0.0451765 0.0572925 0.0781286 0.0561985 0.00812596 49
produce:opflash:OpFlashFinderVerticalDrift 0.00375329 0.0156016 0.0295514 0.0147576 0.00545419 49
produce:wclsdatavd:WireCellToolkit 27.8887 42.2549 72.4493 37.6161 11.2698 49
produce:gaushit:GausHitFinder 0.318645 0.754007 1.44122 0.729293 0.245207 49
produce:nhitsfilter:NumberOfHitsFilter 0.000158317 0.000385428 0.00104933 0.000328978 0.000195522 49
produce:reco3d:SpacePointSolver 2.07349 6.07234 18.8623 5.27341 3.38931 49
produce:hitpdune:DisambigFromSpacePoints 0.0223252 0.104404 0.476231 0.073089 0.0899743 49
produce:pandora:StandardPandora 2.22875 32.9965 380.924 15.6338 71.1593 49
produce:pandoraTrack:LArPandoraTrackCreation 0.107181 0.382169 1.50334 0.275212 0.279895 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00685213 0.0209933 0.0447154 0.0198596 0.00753882 49
[art]:TriggerResults:TriggerResultInserter 2.3865e-05 5.09093e-05 0.000101631 4.808e-05 1.37142e-05 49
end_path:out1:RootOutput 5.26e-06 1.28579e-05 2.646e-05 1.3155e-05 3.77337e-06 49
end_path:out1:RootOutput(write) 1.88622 2.78355 3.68477 2.72129 0.371201 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4728.77 MB
Peak resident set size usage (VmHWM): 2669.31 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0385_df-s05-d4_dw_0_20250918T070835_reco_stage1_20250918T092411_keepup.root
\tHists: np02vd_raw_run039433_0385_df-s05-d4_dw_0_20250918T070835_reco_stage1_20250918T092411_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0385_df-s05-d4_dw_0_20250918T070835_reco_stage1_20250918T092411_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0385_df-s05-d4_dw_0_20250918T070835_reco_stage1_20250918T092411_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_10_00d00",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1758187452.0,
"core.end_time": 1758187452.0,
"core.events": [
110302,
110308,
110314,
110320,
110326,
110332,
110338,
110344,
110350,
110356,
110362,
110368,
110374,
110380,
110386,
110392,
110398,
110404,
110410,
110416,
110422,
110428,
110434,
110440,
110446,
110452,
110458,
110464,
110470,
110476,
110482,
110488,
110494,
110500,
110506,
110512,
110518,
110524,
110530,
110536,
110542,
110548,
110554,
110560,
110566,
110572,
110578,
110584,
110590
],
"core.event_count": 49,
"core.first_event_number": 110302,
"core.last_event_number": 110590,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0385_df-s05-d4_dw_0_20250918T070835.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0385_df-s05-d4_dw_0_20250918T070835_reco_stage1_20250918T092411_keepup_hists.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "root",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_10_00d00",
"core.data_tier": "root-tuple-virtual",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1758187452.0,
"core.end_time": 1758187452.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0385_df-s05-d4_dw_0_20250918T070835.hdf5"
}
]
} 