Jobsub ID 242087.63@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
roc:anode0sigproc0> EOS at call=45 anode=0
[01:52:25.673] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[01:52:25.673] D [ glue ] frame sink sees EOS
[01:52:25.673] D [ pgraph ] <Pgrapher:> graph execution complete
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 26.91 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.83 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.69 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.32 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.29 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.18 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.58 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.55 sec
[01:52:25.674] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[01:52:25.674] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[01:52:25.674] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[01:52:25.674] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[01:52:25.674] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[01:52:25.674] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.01 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:52:25.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:52:25.674] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[01:52:25.674] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[01:52:25.674] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[01:52:25.674] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[01:52:25.674] I [ timer ] Timer: Total node execution : 127.35999941267073 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 59592 traces tagged "gauss"
FrameSaver: q=3.32127e+07 n=1552302 tag=gauss
wclsFrameSaver: saving 74196 traces tagged "wiener"
FrameSaver: q=3.5484e+07 n=1507738 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 768 XUs and 926 XVs -> 29 XUVs
C:0 T:1 923 XUs and 932 XVs -> 55 XUVs
C:0 T:2 868 XUs and 1118 XVs -> 68 XUVs
C:0 T:3 3976 XUs and 4742 XVs -> 253 XUVs
C:0 T:4 1761 XUs and 2082 XVs -> 53 XUVs
C:0 T:5 790 XUs and 959 XVs -> 61 XUVs
C:0 T:6 41982 XUs and 45497 XVs -> 3436 XUVs
C:0 T:7 1191 XUs and 1514 XVs -> 34 XUVs
C:0 T:8 4788 XUs and 4193 XVs -> 285 XUVs
C:0 T:9 733 XUs and 806 XVs -> 65 XUVs
C:0 T:10 5693 XUs and 3607 XVs -> 496 XUVs
C:0 T:11 938 XUs and 1091 XVs -> 43 XUVs
C:0 T:12 1256 XUs and 1059 XVs -> 53 XUVs
C:0 T:13 832 XUs and 1094 XVs -> 64 XUVs
C:0 T:14 587 XUs and 866 XVs -> 41 XUVs
C:0 T:15 1563 XUs and 1476 XVs -> 91 XUVs
5127 XUVs total
2421 collection wire objects
5127 potential space points
Neighbour search...
189001 tests to find 46470 neighbours
Iterating with no regularization...
Begin: 3.18052e+11
0 3.09603e+11
1 3.09115e+11
2 3.09101e+11
Now with regularization...
Begin: 3.07856e+11
0 3.07851e+11
03-Nov-2025 01:54:33 CET Closed output file "np02vd_raw_run040346_2245_df-s04-d0_dw_0_20251102T192827_reco_stage1_20251103T005433_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 137.855 612.07 7546.4 216.365 1523.19 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.6564e-05 0.000215665 0.000623171 0.000130105 0.000166158 23
produce:tpcrawdecoder:PDVDTPCReader 31.7892 63.0586 97.2387 61.4085 12.4641 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.547617 0.736442 1.84476 0.636584 0.267893 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000399651 0.000479024 0.000818849 0.000459013 7.99685e-05 23
produce:ophit:OpHitFinder 6.7547e-05 0.000205392 0.00070261 0.00015971 0.000142889 23
produce:opflash:OpFlashFinderVerticalDrift 5.862e-05 9.48272e-05 0.000379783 8.016e-05 6.24695e-05 23
produce:wclsdatavd:WireCellToolkit 57.1713 98.7282 177.958 94.5272 27.3049 23
produce:gaushit:GausHitFinder 0.946816 1.59164 4.31448 1.40036 0.734856 23
produce:nhitsfilter:NumberOfHitsFilter 0.00026071 0.000404832 0.000892167 0.000358264 0.000149014 23
produce:reco3d:SpacePointSolver 8.19007 19.032 100.156 13.8827 20.1885 23
produce:hitpdune:DisambigFromSpacePoints 0.101435 0.375526 2.35959 0.215785 0.52995 23
produce:pandora:StandardPandora 13.2667 419.428 7161.57 30.8108 1476.71 23
produce:pandoraTrack:LArPandoraTrackCreation 0.692478 3.35375 27.4987 1.51885 6.0667 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0252119 0.0453117 0.146598 0.0389816 0.0245552 23
[art]:TriggerResults:TriggerResultInserter 2.7892e-05 6.40208e-05 0.000142708 6.4671e-05 2.46378e-05 23
end_path:out1:RootOutput 7.975e-06 2.3749e-05 8.1083e-05 2.0859e-05 1.7939e-05 23
end_path:out1:RootOutput(write) 4.85036 5.6348 7.96412 5.4541 0.733605 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7864.52 MB
Peak resident set size usage (VmHWM): 5844.52 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_2245_df-s04-d0_dw_0_20251102T192827_reco_stage1_20251103T005433_keepup.root
\tHists: np02vd_raw_run040346_2245_df-s04-d0_dw_0_20251102T192827_reco_stage1_20251103T005433_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_2245_df-s04-d0_dw_0_20251102T192827_reco_stage1_20251103T005433_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_2245_df-s04-d0_dw_0_20251102T192827_reco_stage1_20251103T005433_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_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": 1762131274.0,
"core.end_time": 1762131274.0,
"core.events": [
207228,
207232,
207236,
207240,
207244,
207248,
207252,
207256,
207260,
207264,
207268,
207272,
207276,
207280,
207284,
207288,
207292,
207296,
207300,
207304,
207308,
207312,
207316
],
"core.event_count": 23,
"core.first_event_number": 207228,
"core.last_event_number": 207316,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_2245_df-s04-d0_dw_0_20251102T192827.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_2245_df-s04-d0_dw_0_20251102T192827_reco_stage1_20251103T005433_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_11_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": 1762131274.0,
"core.end_time": 1762131274.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_2245_df-s04-d0_dw_0_20251102T192827.hdf5"
}
]
} 