Jobsub ID 242471.62@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
49.673] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[20:32:49.673] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[20:32:49.673] D [ glue ] frame sink sees EOS
[20:32:49.673] D [ pgraph ] <Pgrapher:> graph execution complete
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.06 sec
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.99 sec
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.98 sec
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.96 sec
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.93 sec
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.77 sec
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.69 sec
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.65 sec
[20:32:49.673] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[20:32:49.673] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[20:32:49.673] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[20:32:49.673] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[20:32:49.673] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.05 sec
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[20:32:49.673] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:32:49.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:32:49.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:32:49.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:32:49.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:32:49.674] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[20:32:49.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:32:49.674] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:32:49.674] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[20:32:49.674] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[20:32:49.674] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[20:32:49.674] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[20:32:49.674] I [ timer ] Timer: Total node execution : 68.31000020727515 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 52358 traces tagged "gauss"
FrameSaver: q=1.34239e+07 n=1051902 tag=gauss
wclsFrameSaver: saving 67112 traces tagged "wiener"
FrameSaver: q=1.4446e+07 n=992838 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1629 XUs and 2307 XVs -> 70 XUVs
C:0 T:1 11441 XUs and 15919 XVs -> 888 XUVs
C:0 T:2 298 XUs and 263 XVs -> 28 XUVs
C:0 T:3 3048 XUs and 3282 XVs -> 331 XUVs
C:0 T:4 1862 XUs and 1161 XVs -> 143 XUVs
C:0 T:5 809 XUs and 390 XVs -> 30 XUVs
C:0 T:6 992 XUs and 676 XVs -> 63 XUVs
C:0 T:7 1053 XUs and 1007 XVs -> 54 XUVs
C:0 T:8 294 XUs and 308 XVs -> 25 XUVs
C:0 T:9 2248 XUs and 2152 XVs -> 145 XUVs
C:0 T:10 1407 XUs and 1471 XVs -> 81 XUVs
C:0 T:11 1028 XUs and 1447 XVs -> 48 XUVs
C:0 T:12 1897 XUs and 2242 XVs -> 113 XUVs
C:0 T:13 1168 XUs and 1675 XVs -> 278 XUVs
C:0 T:14 2447 XUs and 2162 XVs -> 118 XUVs
C:0 T:15 2165 XUs and 3047 XVs -> 375 XUVs
2790 XUVs total
1893 collection wire objects
2790 potential space points
Neighbour search...
73468 tests to find 28116 neighbours
Iterating with no regularization...
Begin: 3.67264e+11
0 3.67117e+11
Now with regularization...
Begin: 3.67058e+11
0 3.67056e+11
03-Nov-2025 20:33:45 GMT Closed output file "np02vd_raw_run040346_3748_df-s04-d0_dw_0_20251103T165523_reco_stage1_20251103T203345_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 211.45 411.313 1098.72 364.871 207.588 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.8495e-05 0.000306061 0.00310431 0.000171052 0.000599147 23
produce:tpcrawdecoder:PDVDTPCReader 95.7833 154.489 283.722 152.164 41.3488 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.815474 1.43381 3.36169 1.26547 0.641644 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000442532 0.000626516 0.00273028 0.00053153 0.00045368 23
produce:ophit:OpHitFinder 9.8565e-05 0.000274285 0.00220128 0.000178316 0.000414228 23
produce:opflash:OpFlashFinderVerticalDrift 7.5522e-05 0.000200753 0.00148434 0.000141737 0.000277934 23
produce:wclsdatavd:WireCellToolkit 67.322 100.828 170.929 101.704 26.8264 23
produce:gaushit:GausHitFinder 1.29834 1.96659 3.10521 1.87138 0.525072 23
produce:nhitsfilter:NumberOfHitsFilter 0.000282311 0.000541272 0.00166855 0.000459073 0.000274056 23
produce:reco3d:SpacePointSolver 9.97229 17.9375 38.958 16.9483 7.02676 23
produce:hitpdune:DisambigFromSpacePoints 0.152677 0.390823 1.02872 0.266146 0.249988 23
produce:pandora:StandardPandora 20.7486 124.166 672.712 44.7724 173.771 23
produce:pandoraTrack:LArPandoraTrackCreation 0.832616 3.23861 12.4795 2.19512 2.78357 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0353879 0.0617564 0.0919893 0.0617869 0.0147234 23
[art]:TriggerResults:TriggerResultInserter 5.5935e-05 9.98235e-05 0.000219272 8.9849e-05 3.4294e-05 23
end_path:out1:RootOutput 1.063e-05 2.44555e-05 8.5761e-05 1.8956e-05 1.827e-05 23
end_path:out1:RootOutput(write) 5.22774 6.64933 10.1892 6.68558 1.02316 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6333.86 MB
Peak resident set size usage (VmHWM): 4247.71 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3748_df-s04-d0_dw_0_20251103T165523_reco_stage1_20251103T203345_keepup.root
\tHists: np02vd_raw_run040346_3748_df-s04-d0_dw_0_20251103T165523_reco_stage1_20251103T203346_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3748_df-s04-d0_dw_0_20251103T165523_reco_stage1_20251103T203345_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3748_df-s04-d0_dw_0_20251103T165523_reco_stage1_20251103T203345_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": 1762202027.0,
"core.end_time": 1762202027.0,
"core.events": [
345984,
345988,
345992,
345996,
346000,
346004,
346008,
346012,
346016,
346020,
346024,
346028,
346032,
346036,
346040,
346044,
346048,
346052,
346056,
346060,
346064,
346068,
346072
],
"core.event_count": 23,
"core.first_event_number": 345984,
"core.last_event_number": 346072,
"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_3748_df-s04-d0_dw_0_20251103T165523.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3748_df-s04-d0_dw_0_20251103T165523_reco_stage1_20251103T203346_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": 1762202027.0,
"core.end_time": 1762202027.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_3748_df-s04-d0_dw_0_20251103T165523.hdf5"
}
]
} 