Jobsub ID 49096.75@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ue ] <ChannelSelector:chsel0> see EOS at call=40
[02:58:43.196] D [ aux ] <Resampler:resmp0> EOS at call=81
[02:58:43.196] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0
[02:58:43.196] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[02:58:43.196] D [ glue ] frame sink sees EOS
[02:58:43.197] D [ pgraph ] <Pgrapher:> graph execution complete
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 18.39 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.27 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.98 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.69 sec
[02:58:43.197] I [ timer ] Timer: WireCell::Aux::Resampler : 0.81 sec
[02:58:43.197] I [ timer ] Timer: WireCell::Aux::Resampler : 0.79 sec
[02:58:43.197] I [ timer ] Timer: WireCell::Aux::Resampler : 0.77 sec
[02:58:43.197] I [ timer ] Timer: WireCell::Aux::Resampler : 0.77 sec
[02:58:43.197] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[02:58:43.197] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[02:58:43.197] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[02:58:43.197] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[02:58:43.197] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[02:58:43.197] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[02:58:43.197] I [ timer ] Timer: Total node execution : 59.52999895438552 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 15796 traces tagged "gauss"
FrameSaver: q=5.52173e+06 n=429006 tag=gauss
wclsFrameSaver: saving 20266 traces tagged "wiener"
FrameSaver: q=5.8466e+06 n=411338 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 284 XUs and 361 XVs -> 3 XUVs
C:0 T:1 193 XUs and 256 XVs -> 7 XUVs
C:0 T:2 205 XUs and 236 XVs -> 12 XUVs
C:0 T:3 48 XUs and 40 XVs -> 3 XUVs
C:0 T:4 3624 XUs and 5316 XVs -> 246 XUVs
C:0 T:5 179 XUs and 247 XVs -> 10 XUVs
C:0 T:6 18305 XUs and 38351 XVs -> 1421 XUVs
C:0 T:7 173 XUs and 140 XVs -> 2 XUVs
1704 XUVs total
1115 collection wire objects
1704 potential space points
Neighbour search...
30692 tests to find 7090 neighbours
Iterating with no regularization...
Begin: 4.35765e+10
0 4.20934e+10
1 4.20238e+10
2 4.20237e+10
Now with regularization...
Begin: 4.18972e+10
0 4.18971e+10
19-Sep-2025 02:59:25 BST Closed output file "np02vd_raw_run039499_0107_df-s04-d3_dw_0_20250918T211741_reco_stage1_20250919T015925_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 112.117 217.859 1086.44 160.445 184.327 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.3098e-05 0.000176678 0.000345174 0.000157706 7.14809e-05 41
produce:tpcrawdecoder:PDVDTPCReader 10.4394 36.3878 49.7094 36.3013 6.91926 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.371931 0.415183 0.618964 0.394091 0.0506307 41
produce:pdvddaphne:DAPHNEReaderPDVD 7.99545 11.6761 16.0651 11.8744 1.72263 41
produce:ophit:OpHitFinder 0.0488041 0.0810901 0.115792 0.0793425 0.0123629 41
produce:opflash:OpFlashFinderVerticalDrift 0.00892638 0.0209073 0.0338083 0.020226 0.00589834 41
produce:wclsdatavd:WireCellToolkit 48.9257 67.4018 156.035 58.7597 23.3518 41
produce:gaushit:GausHitFinder 0.65294 1.30346 3.04979 1.22211 0.524647 41
produce:nhitsfilter:NumberOfHitsFilter 0.000189994 0.000326433 0.000669319 0.0003166 0.000102493 41
produce:reco3d:SpacePointSolver 2.62024 9.46245 26.6179 8.61487 5.42219 41
produce:hitpdune:DisambigFromSpacePoints 0.0311733 0.21234 0.956064 0.150033 0.194664 41
produce:pandora:StandardPandora 9.79504 85.3702 890.98 32.9662 168.583 41
produce:pandoraTrack:LArPandoraTrackCreation 0.15112 1.12318 5.16847 0.712862 1.05196 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.0103827 0.0314221 0.0950102 0.0295347 0.013911 41
[art]:TriggerResults:TriggerResultInserter 2.6407e-05 5.29036e-05 0.000105609 4.9168e-05 1.75787e-05 41
end_path:out1:RootOutput 5.436e-06 1.18887e-05 3.5734e-05 1.0963e-05 5.19018e-06 41
end_path:out1:RootOutput(write) 2.58126 4.33195 5.54537 4.35599 0.614823 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5620.75 MB
Peak resident set size usage (VmHWM): 3715.7 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0107_df-s04-d3_dw_0_20250918T211741_reco_stage1_20250919T015925_keepup.root
\tHists: np02vd_raw_run039499_0107_df-s04-d3_dw_0_20250918T211741_reco_stage1_20250919T015927_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0107_df-s04-d3_dw_0_20250918T211741_reco_stage1_20250919T015925_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0107_df-s04-d3_dw_0_20250918T211741_reco_stage1_20250919T015925_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": 1758247168.0,
"core.end_time": 1758247168.0,
"core.events": [
26096,
26102,
26108,
26114,
26120,
26126,
26132,
26138,
26144,
26150,
26156,
26162,
26168,
26174,
26180,
26186,
26192,
26198,
26204,
26210,
26216,
26222,
26228,
26234,
26240,
26246,
26252,
26258,
26264,
26270,
26276,
26282,
26288,
26294,
26300,
26306,
26312,
26318,
26324,
26330,
26336
],
"core.event_count": 41,
"core.first_event_number": 26096,
"core.last_event_number": 26336,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0107_df-s04-d3_dw_0_20250918T211741.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0107_df-s04-d3_dw_0_20250918T211741_reco_stage1_20250919T015927_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": 1758247168.0,
"core.end_time": 1758247168.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0107_df-s04-d3_dw_0_20250918T211741.hdf5"
}
]
} 