Jobsub ID 42563.12@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
> EOS at call=81 anode=1
[22:53:26.205] D [ glue ] <ChannelSelector:chsel0> see EOS at call=40
[22:53:26.205] D [ aux ] <Resampler:resmp0> EOS at call=81
[22:53:26.205] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0
[22:53:26.205] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[22:53:26.205] D [ glue ] frame sink sees EOS
[22:53:26.205] D [ pgraph ] <Pgrapher:> graph execution complete
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.13 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.21 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.96 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.8 sec
[22:53:26.205] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[22:53:26.205] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[22:53:26.205] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[22:53:26.205] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[22:53:26.205] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[22:53:26.205] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[22:53:26.205] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[22:53:26.205] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:53:26.205] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[22:53:26.205] I [ timer ] Timer: Total node execution : 38.31000038795173 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 17407 traces tagged "gauss"
FrameSaver: q=2.8479e+06 n=377790 tag=gauss
wclsFrameSaver: saving 22315 traces tagged "wiener"
FrameSaver: q=3.08013e+06 n=363247 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 82 XUs and 91 XVs -> 5 XUVs
C:0 T:1 578 XUs and 641 XVs -> 32 XUVs
C:0 T:2 11460 XUs and 11803 XVs -> 1060 XUVs
C:0 T:3 1804 XUs and 1790 XVs -> 112 XUVs
C:0 T:4 322 XUs and 529 XVs -> 11 XUVs
C:0 T:5 609 XUs and 1156 XVs -> 53 XUVs
C:0 T:6 665 XUs and 783 XVs -> 51 XUVs
C:0 T:7 847 XUs and 1622 XVs -> 82 XUVs
1406 XUVs total
700 collection wire objects
1406 potential space points
Neighbour search...
47478 tests to find 12682 neighbours
Iterating with no regularization...
Begin: 2.83697e+09
0 2.71387e+09
1 2.70522e+09
2 2.70508e+09
Now with regularization...
Begin: 2.60931e+09
0 2.60926e+09
18-Sep-2025 22:53:43 CEST Closed output file "np02vd_raw_run039499_0019_df-s05-d5_dw_0_20250918T182000_reco_stage1_20250918T205343_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 58.9829 153.879 1542.41 100.096 229.322 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.1508e-05 0.000101981 0.000266639 9.3844e-05 4.40212e-05 41
produce:tpcrawdecoder:PDVDTPCReader 4.66527 6.08934 24.8252 5.49619 3.0567 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.173506 0.181805 0.228704 0.178746 0.00891677 41
produce:pdvddaphne:DAPHNEReaderPDVD 3.87101 4.76168 5.71777 4.83677 0.54397 41
produce:ophit:OpHitFinder 0.034637 0.0531661 0.0716901 0.0586197 0.01041 41
produce:opflash:OpFlashFinderVerticalDrift 0.00632875 0.0171045 0.030439 0.0168098 0.00614626 41
produce:wclsdatavd:WireCellToolkit 35.1401 52.198 82.3416 49.2519 13.2171 41
produce:gaushit:GausHitFinder 0.454603 0.890711 1.65867 0.80975 0.317233 41
produce:nhitsfilter:NumberOfHitsFilter 8.8897e-05 0.000178231 0.000326133 0.000167573 6.63614e-05 41
produce:reco3d:SpacePointSolver 2.40119 7.32987 21.8359 6.02534 3.86569 41
produce:hitpdune:DisambigFromSpacePoints 0.0324525 0.172742 1.02104 0.133033 0.164214 41
produce:pandora:StandardPandora 7.4228 78.2189 1439.22 30.5429 222.448 41
produce:pandoraTrack:LArPandoraTrackCreation 0.207181 0.701798 4.42384 0.507019 0.723724 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.010037 0.0236281 0.0511512 0.0206438 0.0108754 41
[art]:TriggerResults:TriggerResultInserter 2.1254e-05 3.32556e-05 5.6348e-05 3.3541e-05 7.89034e-06 41
end_path:out1:RootOutput 3.169e-06 5.78315e-06 2.3308e-05 4.883e-06 3.99967e-06 41
end_path:out1:RootOutput(write) 2.24232 3.22375 5.2608 2.91473 0.850053 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5454.36 MB
Peak resident set size usage (VmHWM): 3549.06 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0019_df-s05-d5_dw_0_20250918T182000_reco_stage1_20250918T205343_keepup.root
\tHists: np02vd_raw_run039499_0019_df-s05-d5_dw_0_20250918T182000_reco_stage1_20250918T205343_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0019_df-s05-d5_dw_0_20250918T182000_reco_stage1_20250918T205343_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0019_df-s05-d5_dw_0_20250918T182000_reco_stage1_20250918T205343_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": 1758228824.0,
"core.end_time": 1758228824.0,
"core.events": [
4625,
4631,
4637,
4643,
4649,
4655,
4661,
4667,
4673,
4679,
4685,
4691,
4697,
4703,
4709,
4715,
4721,
4727,
4733,
4739,
4745,
4751,
4757,
4763,
4769,
4775,
4781,
4787,
4793,
4799,
4805,
4811,
4817,
4823,
4829,
4835,
4841,
4847,
4853,
4859,
4865
],
"core.event_count": 41,
"core.first_event_number": 4625,
"core.last_event_number": 4865,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0019_df-s05-d5_dw_0_20250918T182000.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0019_df-s05-d5_dw_0_20250918T182000_reco_stage1_20250918T205343_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": 1758228824.0,
"core.end_time": 1758228824.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0019_df-s05-d5_dw_0_20250918T182000.hdf5"
}
]
} 