Jobsub ID 42480.2@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
igproc1> EOS at call=79 anode=1
[20:44:26.558] D [ glue ] <ChannelSelector:chsel0> see EOS at call=39
[20:44:26.558] D [ aux ] <Resampler:resmp0> EOS at call=79
[20:44:26.558] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[20:44:26.558] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[20:44:26.558] D [ glue ] frame sink sees EOS
[20:44:26.558] D [ pgraph ] <Pgrapher:> graph execution complete
[20:44:26.558] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.04 sec
[20:44:26.558] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.89 sec
[20:44:26.558] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.6 sec
[20:44:26.558] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.84 sec
[20:44:26.558] I [ timer ] Timer: WireCell::Aux::Resampler : 0.58 sec
[20:44:26.558] I [ timer ] Timer: WireCell::Aux::Resampler : 0.56 sec
[20:44:26.558] I [ timer ] Timer: WireCell::Aux::Resampler : 0.55 sec
[20:44:26.558] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[20:44:26.559] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[20:44:26.559] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[20:44:26.559] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[20:44:26.559] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[20:44:26.559] I [ timer ] Timer: Total node execution : 46.580000845715404 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25109 traces tagged "gauss"
FrameSaver: q=5.33609e+06 n=588986 tag=gauss
wclsFrameSaver: saving 31739 traces tagged "wiener"
FrameSaver: q=5.89549e+06 n=569985 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 360 XUs and 348 XVs -> 15 XUVs
C:0 T:1 621 XUs and 490 XVs -> 24 XUVs
C:0 T:2 745 XUs and 942 XVs -> 74 XUVs
C:0 T:3 2427 XUs and 3100 XVs -> 254 XUVs
C:0 T:4 588 XUs and 523 XVs -> 17 XUVs
C:0 T:5 351 XUs and 295 XVs -> 6 XUVs
C:0 T:6 3577 XUs and 3752 XVs -> 301 XUVs
C:0 T:7 24001 XUs and 32442 XVs -> 2684 XUVs
3375 XUVs total
1384 collection wire objects
3375 potential space points
Neighbour search...
179275 tests to find 40806 neighbours
Iterating with no regularization...
Begin: 8.33773e+09
0 8.02947e+09
1 8.00277e+09
2 8.00143e+09
Now with regularization...
Begin: 7.81997e+09
0 7.8193e+09
18-Sep-2025 20:45:33 BST Closed output file "np02vd_raw_run039499_0007_df-s04-d1_dw_0_20250918T175638_reco_stage1_20250918T194533_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 74.9201 130.321 203.62 126.782 30.7288 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.986e-05 0.000136843 0.000346923 0.000116068 6.04493e-05 40
produce:tpcrawdecoder:PDVDTPCReader 22.1801 36.1721 58.6588 34.0738 7.67264 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.373644 0.427156 0.762939 0.399325 0.0714066 40
produce:pdvddaphne:DAPHNEReaderPDVD 5.15613 7.7336 11.5476 7.62514 1.61874 40
produce:ophit:OpHitFinder 0.0487982 0.0601067 0.0988794 0.0590853 0.00823268 40
produce:opflash:OpFlashFinderVerticalDrift 0.010934 0.0167849 0.0753417 0.0146383 0.00966617 40
produce:wclsdatavd:WireCellToolkit 29.0792 42.2931 72.8147 40.7096 9.96048 40
produce:gaushit:GausHitFinder 0.398915 0.832741 1.32827 0.801596 0.219772 40
produce:nhitsfilter:NumberOfHitsFilter 0.000131517 0.000212893 0.000473511 0.000190844 6.89423e-05 40
produce:reco3d:SpacePointSolver 2.18915 7.35312 15.0905 7.13559 3.1325 40
produce:hitpdune:DisambigFromSpacePoints 0.0221669 0.113264 0.312716 0.111297 0.0682748 40
produce:pandora:StandardPandora 4.24981 31.5304 95.907 23.1152 21.5927 40
produce:pandoraTrack:LArPandoraTrackCreation 0.103645 0.417412 1.18462 0.366482 0.21438 40
produce:pandoraGnocalo:GnocchiCalorimetry 0.0103885 0.0209984 0.0291035 0.019879 0.00486623 40
[art]:TriggerResults:TriggerResultInserter 2.5327e-05 3.90417e-05 0.000133101 3.3463e-05 1.78956e-05 40
end_path:out1:RootOutput 5.28e-06 1.08707e-05 3.0788e-05 1.02595e-05 4.52072e-06 40
end_path:out1:RootOutput(write) 2.76919 3.32339 3.87558 3.3231 0.247606 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4639.17 MB
Peak resident set size usage (VmHWM): 2709.5 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0007_df-s04-d1_dw_0_20250918T175638_reco_stage1_20250918T194533_keepup.root
\tHists: np02vd_raw_run039499_0007_df-s04-d1_dw_0_20250918T175638_reco_stage1_20250918T194533_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0007_df-s04-d1_dw_0_20250918T175638_reco_stage1_20250918T194533_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0007_df-s04-d1_dw_0_20250918T175638_reco_stage1_20250918T194533_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": 1758224734.0,
"core.end_time": 1758224734.0,
"core.events": [
1704,
1710,
1716,
1722,
1728,
1734,
1740,
1746,
1752,
1758,
1764,
1770,
1776,
1782,
1788,
1794,
1800,
1806,
1812,
1818,
1824,
1830,
1836,
1842,
1848,
1854,
1860,
1866,
1872,
1878,
1884,
1890,
1896,
1902,
1908,
1914,
1920,
1926,
1932,
1938
],
"core.event_count": 40,
"core.first_event_number": 1704,
"core.last_event_number": 1938,
"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_0007_df-s04-d1_dw_0_20250918T175638.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0007_df-s04-d1_dw_0_20250918T175638_reco_stage1_20250918T194533_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": 1758224734.0,
"core.end_time": 1758224734.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_0007_df-s04-d1_dw_0_20250918T175638.hdf5"
}
]
} 