Jobsub ID 49050.153@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
01:32.207] D [ glue ] <ChannelSelector:chsel0> see EOS at call=39
[01:01:32.207] D [ aux ] <Resampler:resmp0> EOS at call=79
[01:01:32.207] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[01:01:32.207] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[01:01:32.207] D [ glue ] frame sink sees EOS
[01:01:32.207] D [ pgraph ] <Pgrapher:> graph execution complete
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.58 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.31 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.38 sec
[01:01:32.207] I [ timer ] Timer: WireCell::Aux::Resampler : 0.39 sec
[01:01:32.207] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[01:01:32.207] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[01:01:32.207] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[01:01:32.207] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[01:01:32.207] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[01:01:32.207] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:01:32.207] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[01:01:32.207] I [ timer ] Timer: Total node execution : 38.56000044196844 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 23157 traces tagged "gauss"
FrameSaver: q=3.32078e+06 n=498961 tag=gauss
wclsFrameSaver: saving 29223 traces tagged "wiener"
FrameSaver: q=3.54688e+06 n=476266 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1891 XUs and 1472 XVs -> 55 XUVs
C:0 T:1 11237 XUs and 10775 XVs -> 794 XUVs
C:0 T:2 393 XUs and 351 XVs -> 13 XUVs
C:0 T:3 1277 XUs and 1211 XVs -> 56 XUVs
C:0 T:4 153 XUs and 108 XVs -> 5 XUVs
C:0 T:5 2535 XUs and 1808 XVs -> 54 XUVs
C:0 T:6 1016 XUs and 1161 XVs -> 55 XUVs
C:0 T:7 333 XUs and 355 XVs -> 17 XUVs
1049 XUVs total
723 collection wire objects
1049 potential space points
Neighbour search...
28255 tests to find 7086 neighbours
Iterating with no regularization...
Begin: 7.44751e+08
0 6.99049e+08
1 6.9689e+08
2 6.96864e+08
Now with regularization...
Begin: 6.83528e+08
0 6.83501e+08
19-Sep-2025 01:02:02 BST Closed output file "np02vd_raw_run039499_0078_df-s04-d2_dw_0_20250918T201952_reco_stage1_20250919T000202_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 103.77 166.847 1255.75 128.406 179.913 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 0.000102633 0.000181748 0.000387208 0.000167134 5.76833e-05 40
produce:tpcrawdecoder:PDVDTPCReader 27.7573 38.6388 52.1232 37.474 5.1303 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.383059 0.442369 0.622572 0.421267 0.054399 40
produce:pdvddaphne:DAPHNEReaderPDVD 5.98571 8.83012 12.5425 8.81395 1.47769 40
produce:ophit:OpHitFinder 0.038281 0.0684402 0.110017 0.0641939 0.0154175 40
produce:opflash:OpFlashFinderVerticalDrift 0.00865525 0.0175073 0.0415083 0.0162229 0.00597893 40
produce:wclsdatavd:WireCellToolkit 36.7511 49.7434 87.5056 45.5809 11.2709 40
produce:gaushit:GausHitFinder 0.477968 0.912703 2.09682 0.823824 0.309304 40
produce:nhitsfilter:NumberOfHitsFilter 0.000131347 0.000397694 0.00093038 0.000367256 0.000201004 40
produce:reco3d:SpacePointSolver 2.845 7.53717 22.1606 7.26933 3.54395 40
produce:hitpdune:DisambigFromSpacePoints 0.0292851 0.119614 0.446938 0.102518 0.0788136 40
produce:pandora:StandardPandora 5.79124 56.4467 1083.62 23.3863 169.165 40
produce:pandoraTrack:LArPandoraTrackCreation 0.0928667 0.48313 2.47847 0.404639 0.39934 40
produce:pandoraGnocalo:GnocchiCalorimetry 0.00703026 0.023861 0.0475452 0.023453 0.00827933 40
[art]:TriggerResults:TriggerResultInserter 3.7741e-05 6.37959e-05 0.000115728 6.5759e-05 1.53013e-05 40
end_path:out1:RootOutput 6.141e-06 1.55657e-05 4.2911e-05 1.35105e-05 7.71399e-06 40
end_path:out1:RootOutput(write) 3.00183 3.52526 4.48305 3.38679 0.386815 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4932.3 MB
Peak resident set size usage (VmHWM): 2982.32 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0078_df-s04-d2_dw_0_20250918T201952_reco_stage1_20250919T000202_keepup.root
\tHists: np02vd_raw_run039499_0078_df-s04-d2_dw_0_20250918T201952_reco_stage1_20250919T000202_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0078_df-s04-d2_dw_0_20250918T201952_reco_stage1_20250919T000202_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0078_df-s04-d2_dw_0_20250918T201952_reco_stage1_20250919T000202_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": 1758240123.0,
"core.end_time": 1758240123.0,
"core.events": [
19039,
19045,
19051,
19057,
19063,
19069,
19075,
19081,
19087,
19093,
19099,
19105,
19111,
19117,
19123,
19129,
19135,
19141,
19147,
19153,
19159,
19165,
19171,
19177,
19183,
19189,
19195,
19201,
19207,
19213,
19219,
19225,
19231,
19237,
19243,
19249,
19255,
19261,
19267,
19273
],
"core.event_count": 40,
"core.first_event_number": 19039,
"core.last_event_number": 19273,
"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_0078_df-s04-d2_dw_0_20250918T201952.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0078_df-s04-d2_dw_0_20250918T201952_reco_stage1_20250919T000202_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": 1758240123.0,
"core.end_time": 1758240123.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_0078_df-s04-d2_dw_0_20250918T201952.hdf5"
}
]
} 