Jobsub ID 41739.103@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
call=93
[11:32:45.882] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=93 anode=0
[11:32:45.883] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[11:32:45.883] D [ glue ] frame sink sees EOS
[11:32:45.883] D [ pgraph ] <Pgrapher:> graph execution complete
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.13 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.88 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.78 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.34 sec
[11:32:45.883] I [ timer ] Timer: WireCell::Aux::Resampler : 1.46 sec
[11:32:45.883] I [ timer ] Timer: WireCell::Aux::Resampler : 1.35 sec
[11:32:45.883] I [ timer ] Timer: WireCell::Aux::Resampler : 1.04 sec
[11:32:45.883] I [ timer ] Timer: WireCell::Aux::Resampler : 0.96 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:32:45.883] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:32:45.883] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:32:45.883] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:32:45.883] I [ timer ] Timer: Total node execution : 41.97000011615455 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 17616 traces tagged "gauss"
FrameSaver: q=1.99057e+06 n=338013 tag=gauss
wclsFrameSaver: saving 22886 traces tagged "wiener"
FrameSaver: q=2.14554e+06 n=318125 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 137 XUs and 227 XVs -> 4 XUVs
C:0 T:1 887 XUs and 719 XVs -> 21 XUVs
C:0 T:2 597 XUs and 705 XVs -> 27 XUVs
C:0 T:3 887 XUs and 444 XVs -> 76 XUVs
C:0 T:4 3281 XUs and 2161 XVs -> 185 XUVs
C:0 T:5 2351 XUs and 1714 XVs -> 195 XUVs
C:0 T:6 1476 XUs and 658 XVs -> 200 XUVs
C:0 T:7 109 XUs and 239 XVs -> 6 XUVs
714 XUVs total
546 collection wire objects
714 potential space points
Neighbour search...
10680 tests to find 5352 neighbours
Iterating with no regularization...
Begin: 8.29598e+07
0 7.54596e+07
1 7.53889e+07
Now with regularization...
Begin: 7.44693e+07
0 7.44672e+07
18-Sep-2025 11:32:58 CEST Closed output file "np02vd_raw_run039433_0390_df-s05-d4_dw_0_20250918T072035_reco_stage1_20250918T093258_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 49.2601 102.366 590.717 77.969 87.3871 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.645e-05 0.000127038 0.000342762 0.000114111 5.00073e-05 47
produce:tpcrawdecoder:PDVDTPCReader 6.04728 10.2377 18.8741 9.40961 3.52399 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.173206 0.191678 0.475832 0.179357 0.0470614 47
produce:pdvddaphne:DAPHNEReaderPDVD 2.64535 4.70941 15.8657 3.9644 2.27657 47
produce:ophit:OpHitFinder 0.0367087 0.0681221 0.337074 0.0579986 0.0496961 47
produce:opflash:OpFlashFinderVerticalDrift 0.00274211 0.014925 0.104185 0.0121096 0.0152739 47
produce:wclsdatavd:WireCellToolkit 25.9249 45.2191 141.563 37.4798 23.1076 47
produce:gaushit:GausHitFinder 0.300898 0.828394 4.82372 0.584979 0.763449 47
produce:nhitsfilter:NumberOfHitsFilter 9.6491e-05 0.000217316 0.000673203 0.000187561 0.000115145 47
produce:reco3d:SpacePointSolver 1.86698 6.26062 30.8404 4.21992 5.54424 47
produce:hitpdune:DisambigFromSpacePoints 0.0211501 0.102778 0.824881 0.0536519 0.149748 47
produce:pandora:StandardPandora 3.63952 31.1996 455.459 12.3427 72.242 47
produce:pandoraTrack:LArPandoraTrackCreation 0.111817 0.438897 4.1757 0.251307 0.676309 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00778362 0.0213417 0.11663 0.0170089 0.0181148 47
[art]:TriggerResults:TriggerResultInserter 1.8901e-05 3.12307e-05 9.3331e-05 2.854e-05 1.20347e-05 47
end_path:out1:RootOutput 4.4e-06 9.336e-06 4.2031e-05 6.94e-06 7.2878e-06 47
end_path:out1:RootOutput(write) 1.92857 3.0506 9.23658 2.84123 1.19303 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5458.02 MB
Peak resident set size usage (VmHWM): 3563.69 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0390_df-s05-d4_dw_0_20250918T072035_reco_stage1_20250918T093258_keepup.root
\tHists: np02vd_raw_run039433_0390_df-s05-d4_dw_0_20250918T072035_reco_stage1_20250918T093300_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0390_df-s05-d4_dw_0_20250918T072035_reco_stage1_20250918T093258_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0390_df-s05-d4_dw_0_20250918T072035_reco_stage1_20250918T093258_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": 1758187981.0,
"core.end_time": 1758187981.0,
"core.events": [
111736,
111742,
111748,
111754,
111760,
111766,
111772,
111778,
111784,
111790,
111796,
111802,
111808,
111814,
111820,
111826,
111832,
111838,
111844,
111850,
111856,
111862,
111868,
111874,
111880,
111886,
111892,
111898,
111904,
111910,
111916,
111922,
111928,
111934,
111940,
111946,
111952,
111958,
111964,
111970,
111976,
111982,
111988,
111994,
112000,
112006,
112012
],
"core.event_count": 47,
"core.first_event_number": 111736,
"core.last_event_number": 112012,
"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_0390_df-s05-d4_dw_0_20250918T072035.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0390_df-s05-d4_dw_0_20250918T072035_reco_stage1_20250918T093300_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": 1758187981.0,
"core.end_time": 1758187981.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_0390_df-s05-d4_dw_0_20250918T072035.hdf5"
}
]
} 