Jobsub ID 223125.183@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
Resampler:resmp0> EOS at call=41
[08:48:06.233] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=41 anode=0
[08:48:06.233] D [ glue ] <FrameFanin:nfsp> EOS at call=41 with 8
[08:48:06.233] D [ glue ] frame sink sees EOS
[08:48:06.233] D [ pgraph ] <Pgrapher:> graph execution complete
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.83 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.93 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.67 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.51 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.5 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.33 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.33 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.29 sec
[08:48:06.233] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[08:48:06.233] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[08:48:06.233] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[08:48:06.233] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[08:48:06.233] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[08:48:06.233] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:48:06.233] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:48:06.234] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:48:06.234] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:48:06.234] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[08:48:06.234] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:48:06.234] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:48:06.234] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[08:48:06.234] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[08:48:06.234] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[08:48:06.234] I [ timer ] Timer: Total node execution : 46.399999879300594 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 41887 traces tagged "gauss"
FrameSaver: q=7.75683e+06 n=871441 tag=gauss
wclsFrameSaver: saving 52713 traces tagged "wiener"
FrameSaver: q=8.32655e+06 n=832280 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 19679 XUs and 29380 XVs -> 1881 XUVs
C:0 T:1 320 XUs and 369 XVs -> 49 XUVs
C:0 T:2 1123 XUs and 1580 XVs -> 77 XUVs
C:0 T:3 227 XUs and 221 XVs -> 10 XUVs
C:0 T:4 929 XUs and 1174 XVs -> 155 XUVs
C:0 T:5 424 XUs and 541 XVs -> 29 XUVs
C:0 T:6 1615 XUs and 1574 XVs -> 87 XUVs
C:0 T:7 664 XUs and 1166 XVs -> 47 XUVs
C:0 T:8 829 XUs and 584 XVs -> 48 XUVs
C:0 T:9 2415 XUs and 2125 XVs -> 126 XUVs
C:0 T:10 758 XUs and 1032 XVs -> 42 XUVs
C:0 T:11 3838 XUs and 3714 XVs -> 165 XUVs
C:0 T:12 894 XUs and 1259 XVs -> 84 XUVs
C:0 T:13 1124 XUs and 1012 XVs -> 83 XUVs
C:0 T:14 374 XUs and 438 XVs -> 31 XUVs
C:0 T:15 265 XUs and 268 XVs -> 17 XUVs
2931 XUVs total
1776 collection wire objects
2931 potential space points
Neighbour search...
96881 tests to find 24040 neighbours
Iterating with no regularization...
Begin: 1.30214e+10
0 1.24704e+10
1 1.23726e+10
2 1.23725e+10
Now with regularization...
Begin: 1.22654e+10
0 1.22654e+10
03-Oct-2025 08:48:43 BST Closed output file "np02vd_raw_run039812_0303_df-s03-d0_dw_0_20251003T005533_reco_stage1_20251003T074843_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 176.102 351.272 1248.83 271.486 246.502 21
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 4.9829e-05 8.65253e-05 0.000159427 8.7572e-05 2.19904e-05 21
produce:tpcrawdecoder:PDVDTPCReader 74.7369 113.021 172.517 111.588 27.5302 21
produce:triggerrawdecoder:PDVDTriggerReader4 0.437088 0.565329 0.812888 0.561854 0.0936691 21
produce:pdvddaphne:DAPHNEReaderPDVD 5.25769 11.0547 21.1483 10.6991 3.60824 21
produce:ophit:OpHitFinder 0.0246239 0.0280006 0.0339237 0.0275283 0.0021962 21
produce:opflash:OpFlashFinderVerticalDrift 0.0036681 0.00752183 0.0118929 0.00757703 0.00176313 21
produce:wclsdatavd:WireCellToolkit 40.7283 58.8321 104.024 54.8833 17.7471 21
produce:gaushit:GausHitFinder 0.595419 1.07332 2.32705 0.902902 0.471372 21
produce:nhitsfilter:NumberOfHitsFilter 0.000254969 0.000512011 0.00110377 0.00044409 0.000231658 21
produce:reco3d:SpacePointSolver 6.8712 16.6729 45.6235 12.9297 10.5536 21
produce:hitpdune:DisambigFromSpacePoints 0.0744496 0.290114 0.938065 0.159144 0.256199 21
produce:pandora:StandardPandora 16.1465 143.992 962.573 45.06 219.156 21
produce:pandoraTrack:LArPandoraTrackCreation 0.57887 2.40661 6.78587 2.00727 1.66911 21
produce:pandoraGnocalo:GnocchiCalorimetry 0.0100316 0.0236388 0.0503425 0.0219193 0.00918181 21
[art]:TriggerResults:TriggerResultInserter 1.8672e-05 2.77507e-05 5.6582e-05 2.5193e-05 9.31075e-06 21
end_path:out1:RootOutput 7.933e-06 1.12939e-05 2.3493e-05 9.519e-06 4.03418e-06 21
end_path:out1:RootOutput(write) 2.61881 3.2208 4.60146 3.00604 0.552206 21
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6836.3 MB
Peak resident set size usage (VmHWM): 4934.72 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039812_0303_df-s03-d0_dw_0_20251003T005533_reco_stage1_20251003T074843_keepup.root
\tHists: np02vd_raw_run039812_0303_df-s03-d0_dw_0_20251003T005533_reco_stage1_20251003T074847_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039812_0303_df-s03-d0_dw_0_20251003T005533_reco_stage1_20251003T074843_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039812_0303_df-s03-d0_dw_0_20251003T005533_reco_stage1_20251003T074843_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": 1759477728.0,
"core.end_time": 1759477728.0,
"core.events": [
51528,
51536,
51544,
51552,
51560,
51568,
51576,
51584,
51592,
51600,
51608,
51616,
51624,
51632,
51640,
51648,
51656,
51664,
51672,
51680,
51688
],
"core.event_count": 21,
"core.first_event_number": 51528,
"core.last_event_number": 51688,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39812
],
"core.runs_subruns": [
3981200001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039812_0303_df-s03-d0_dw_0_20251003T005533.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039812_0303_df-s03-d0_dw_0_20251003T005533_reco_stage1_20251003T074847_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": 1759477728.0,
"core.end_time": 1759477728.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39812
],
"core.runs_subruns": [
3981200001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039812_0303_df-s03-d0_dw_0_20251003T005533.hdf5"
}
]
} 