Jobsub ID 41739.117@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
c:anode0sigproc0> EOS at call=97 anode=0
[13:11:44.141] D [ glue ] <FrameFanin:nfsp> EOS at call=97 with 8
[13:11:44.141] D [ glue ] frame sink sees EOS
[13:11:44.141] D [ pgraph ] <Pgrapher:> graph execution complete
[13:11:44.141] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.01 sec
[13:11:44.141] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.16 sec
[13:11:44.141] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.01 sec
[13:11:44.141] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.58 sec
[13:11:44.142] I [ timer ] Timer: WireCell::Aux::Resampler : 0.17 sec
[13:11:44.142] I [ timer ] Timer: WireCell::Aux::Resampler : 0.17 sec
[13:11:44.142] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[13:11:44.142] I [ timer ] Timer: WireCell::Aux::Resampler : 0.15 sec
[13:11:44.142] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:11:44.142] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:11:44.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[13:11:44.142] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:11:44.142] I [ timer ] Timer: Total node execution : 34.42000023461878 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25174 traces tagged "gauss"
FrameSaver: q=5.79682e+06 n=603273 tag=gauss
wclsFrameSaver: saving 32817 traces tagged "wiener"
FrameSaver: q=6.23039e+06 n=573446 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1502 XUs and 1466 XVs -> 40 XUVs
C:0 T:1 747 XUs and 485 XVs -> 56 XUVs
C:0 T:2 980 XUs and 598 XVs -> 29 XUVs
C:0 T:3 403 XUs and 445 XVs -> 36 XUVs
C:0 T:4 2278 XUs and 2974 XVs -> 145 XUVs
C:0 T:5 1571 XUs and 1063 XVs -> 80 XUVs
C:0 T:6 4203 XUs and 3566 XVs -> 260 XUVs
C:0 T:7 23509 XUs and 31034 XVs -> 2504 XUVs
3150 XUVs total
1622 collection wire objects
3150 potential space points
Neighbour search...
124926 tests to find 27254 neighbours
Iterating with no regularization...
Begin: 2.12874e+10
0 2.06387e+10
1 2.0602e+10
2 2.06006e+10
Now with regularization...
Begin: 2.04218e+10
0 2.04213e+10
18-Sep-2025 13:12:24 CEST Closed output file "np02vd_raw_run039433_0392_df-s05-d1_dw_0_20250918T072605_reco_stage1_20250918T111224_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 101.334 213.251 1935.17 134.001 285.69 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.6252e-05 8.82618e-05 0.000300619 8.0043e-05 3.28303e-05 49
produce:tpcrawdecoder:PDVDTPCReader 45.0222 71.7914 256.456 67.0834 30.6814 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.582384 3.34035 125.677 0.736751 17.6587 49
produce:pdvddaphne:DAPHNEReaderPDVD 3.3093 14.4519 22.3693 14.8224 3.64799 49
produce:ophit:OpHitFinder 0.0272504 0.040911 0.0598866 0.0406509 0.00731769 49
produce:opflash:OpFlashFinderVerticalDrift 0.00394864 0.0105488 0.0594462 0.00961567 0.0076354 49
produce:wclsdatavd:WireCellToolkit 21.0852 59.5725 903.88 27.1624 140.456 49
produce:gaushit:GausHitFinder 0.217851 0.561758 1.27984 0.521598 0.191658 49
produce:nhitsfilter:NumberOfHitsFilter 5.7892e-05 0.000215683 0.000580477 0.000169915 0.000130589 49
produce:reco3d:SpacePointSolver 1.60356 13.0415 139.472 5.22618 30.3752 49
produce:hitpdune:DisambigFromSpacePoints 0.0220504 0.0756302 0.320555 0.0608078 0.054734 49
produce:pandora:StandardPandora 2.83553 48.0936 516.336 16.1007 106.317 49
produce:pandoraTrack:LArPandoraTrackCreation 0.0407307 0.309645 0.941307 0.263395 0.202447 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00457549 0.0149239 0.0310646 0.013287 0.00561847 49
[art]:TriggerResults:TriggerResultInserter 1.2691e-05 2.35035e-05 6.5102e-05 1.935e-05 1.19125e-05 49
end_path:out1:RootOutput 2.99e-06 4.72173e-06 2.7591e-05 4.11e-06 3.38635e-06 49
end_path:out1:RootOutput(write) 1.54123 1.9247 2.61781 1.89258 0.230061 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4876.07 MB
Peak resident set size usage (VmHWM): 2955.3 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0392_df-s05-d1_dw_0_20250918T072605_reco_stage1_20250918T111224_keepup.root
\tHists: np02vd_raw_run039433_0392_df-s05-d1_dw_0_20250918T072605_reco_stage1_20250918T111224_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0392_df-s05-d1_dw_0_20250918T072605_reco_stage1_20250918T111224_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0392_df-s05-d1_dw_0_20250918T072605_reco_stage1_20250918T111224_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": 1758193945.0,
"core.end_time": 1758193945.0,
"core.events": [
112375,
112381,
112387,
112393,
112399,
112405,
112411,
112417,
112423,
112429,
112435,
112441,
112447,
112453,
112459,
112465,
112471,
112477,
112483,
112489,
112495,
112501,
112507,
112513,
112519,
112525,
112531,
112537,
112543,
112549,
112555,
112561,
112567,
112573,
112579,
112585,
112591,
112597,
112603,
112609,
112615,
112621,
112627,
112633,
112639,
112645,
112651,
112657,
112663
],
"core.event_count": 49,
"core.first_event_number": 112375,
"core.last_event_number": 112663,
"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_0392_df-s05-d1_dw_0_20250918T072605.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0392_df-s05-d1_dw_0_20250918T072605_reco_stage1_20250918T111224_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": 1758193945.0,
"core.end_time": 1758193945.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_0392_df-s05-d1_dw_0_20250918T072605.hdf5"
}
]
} 