Jobsub ID 242278.2@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
1:45:20.406] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[11:45:20.406] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[11:45:20.406] D [ glue ] frame sink sees EOS
[11:45:20.406] D [ pgraph ] <Pgrapher:> graph execution complete
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.73 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.47 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.28 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.87 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.76 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.49 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.48 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.43 sec
[11:45:20.406] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[11:45:20.406] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[11:45:20.406] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[11:45:20.406] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[11:45:20.406] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:45:20.406] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:45:20.406] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:45:20.406] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:45:20.406] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:45:20.406] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:45:20.406] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:45:20.406] I [ timer ] Timer: Total node execution : 65.49999927543104 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 36909 traces tagged "gauss"
FrameSaver: q=1.26665e+07 n=811700 tag=gauss
wclsFrameSaver: saving 45960 traces tagged "wiener"
FrameSaver: q=1.34308e+07 n=775303 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 931 XUs and 930 XVs -> 27 XUVs
C:0 T:1 105 XUs and 95 XVs -> 2 XUVs
C:0 T:2 138 XUs and 206 XVs -> 0 XUVs
C:0 T:3 637 XUs and 786 XVs -> 21 XUVs
C:0 T:4 5600 XUs and 10935 XVs -> 399 XUVs
C:0 T:5 1014 XUs and 1471 XVs -> 131 XUVs
C:0 T:6 211 XUs and 184 XVs -> 4 XUVs
C:0 T:7 872 XUs and 3945 XVs -> 319 XUVs
C:0 T:8 1193 XUs and 1323 XVs -> 102 XUVs
C:0 T:9 2621 XUs and 2661 XVs -> 135 XUVs
C:0 T:10 375 XUs and 557 XVs -> 28 XUVs
C:0 T:11 483 XUs and 531 XVs -> 31 XUVs
C:0 T:12 1420 XUs and 1236 XVs -> 85 XUVs
C:0 T:13 760 XUs and 663 XVs -> 31 XUVs
C:0 T:14 865 XUs and 1045 XVs -> 111 XUVs
C:0 T:15 437 XUs and 329 XVs -> 17 XUVs
1443 XUVs total
922 collection wire objects
1443 potential space points
Neighbour search...
29677 tests to find 15858 neighbours
Iterating with no regularization...
Begin: 1.70705e+11
0 1.70301e+11
1 1.70286e+11
Now with regularization...
Begin: 1.70133e+11
0 1.70132e+11
03-Nov-2025 11:45:48 GMT Closed output file "np02vd_raw_run040346_3251_df-s04-d0_dw_0_20251103T093354_reco_stage1_20251103T114548_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 105.906 163.94 269.506 148.764 52.8998 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.4544e-05 8.26483e-05 0.000159054 7.2198e-05 2.33705e-05 23
produce:tpcrawdecoder:PDVDTPCReader 14.3435 20.3766 41.7621 20.0274 5.33449 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.72412 0.780728 1.17011 0.767299 0.0850646 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000352396 0.000383659 0.000674933 0.000369373 6.3863e-05 23
produce:ophit:OpHitFinder 5.3254e-05 8.38177e-05 0.000500811 6.1105e-05 8.93688e-05 23
produce:opflash:OpFlashFinderVerticalDrift 4.5124e-05 6.24157e-05 0.000276999 5.1997e-05 4.59715e-05 23
produce:wclsdatavd:WireCellToolkit 64.3074 81.6648 118.381 78.078 16.7855 23
produce:gaushit:GausHitFinder 0.779679 1.26631 2.06897 1.23943 0.364494 23
produce:nhitsfilter:NumberOfHitsFilter 0.000249774 0.000384331 0.000594398 0.0003911 8.65528e-05 23
produce:reco3d:SpacePointSolver 6.1094 11.3765 23.0578 11.6178 4.08901 23
produce:hitpdune:DisambigFromSpacePoints 0.0927249 0.2121 0.578733 0.204616 0.108355 23
produce:pandora:StandardPandora 10.6599 42.6045 156.003 24.4007 41.1685 23
produce:pandoraTrack:LArPandoraTrackCreation 0.498807 1.41499 5.46771 1.1855 1.02158 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0185101 0.0310439 0.0565776 0.0299655 0.00863147 23
[art]:TriggerResults:TriggerResultInserter 2.2917e-05 3.26462e-05 5.8104e-05 3.0407e-05 8.35768e-06 23
end_path:out1:RootOutput 7.981e-06 9.81083e-06 2.3954e-05 9.128e-06 3.07633e-06 23
end_path:out1:RootOutput(write) 3.53596 4.1535 4.97605 4.15341 0.465589 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6162.5 MB
Peak resident set size usage (VmHWM): 4121.74 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3251_df-s04-d0_dw_0_20251103T093354_reco_stage1_20251103T114548_keepup.root
\tHists: np02vd_raw_run040346_3251_df-s04-d0_dw_0_20251103T093354_reco_stage1_20251103T114550_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3251_df-s04-d0_dw_0_20251103T093354_reco_stage1_20251103T114548_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3251_df-s04-d0_dw_0_20251103T093354_reco_stage1_20251103T114548_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": 1762170350.0,
"core.end_time": 1762170350.0,
"core.events": [
300072,
300076,
300080,
300084,
300088,
300092,
300096,
300100,
300104,
300108,
300112,
300116,
300120,
300124,
300128,
300132,
300136,
300140,
300144,
300148,
300152,
300156,
300160
],
"core.event_count": 23,
"core.first_event_number": 300072,
"core.last_event_number": 300160,
"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_3251_df-s04-d0_dw_0_20251103T093354.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3251_df-s04-d0_dw_0_20251103T093354_reco_stage1_20251103T114550_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": 1762170350.0,
"core.end_time": 1762170350.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_3251_df-s04-d0_dw_0_20251103T093354.hdf5"
}
]
} 