Jobsub ID 243386.48@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[11:22:27.723] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[11:22:27.723] D [ glue ] frame sink sees EOS
[11:22:27.723] D [ pgraph ] <Pgrapher:> graph execution complete
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.74 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.82 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.85 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.51 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.21 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.16 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.11 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.85 sec
[11:22:27.723] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[11:22:27.723] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[11:22:27.723] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[11:22:27.723] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.03 sec
[11:22:27.723] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:22:27.723] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:22:27.723] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:22:27.723] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:22:27.723] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:22:27.723] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:22:27.723] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:22:27.723] I [ timer ] Timer: Total node execution : 64.50999952852726 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 31693 traces tagged "gauss"
FrameSaver: q=8.556e+06 n=698968 tag=gauss
wclsFrameSaver: saving 39985 traces tagged "wiener"
FrameSaver: q=9.51204e+06 n=672496 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 19930 XUs and 17535 XVs -> 2318 XUVs
C:0 T:1 9002 XUs and 4265 XVs -> 2260 XUVs
C:0 T:2 6854 XUs and 4464 XVs -> 578 XUVs
C:0 T:3 189 XUs and 210 XVs -> 14 XUVs
C:0 T:4 331 XUs and 304 XVs -> 10 XUVs
C:0 T:5 497 XUs and 498 XVs -> 20 XUVs
C:0 T:6 146 XUs and 180 XVs -> 8 XUVs
C:0 T:7 457 XUs and 544 XVs -> 26 XUVs
C:0 T:8 451 XUs and 755 XVs -> 39 XUVs
C:0 T:9 248 XUs and 424 XVs -> 34 XUVs
C:0 T:10 421 XUs and 509 XVs -> 24 XUVs
C:0 T:11 1900 XUs and 3890 XVs -> 178 XUVs
C:0 T:12 137 XUs and 348 XVs -> 7 XUVs
C:0 T:13 404 XUs and 730 XVs -> 29 XUVs
C:0 T:14 1628 XUs and 1487 XVs -> 257 XUVs
C:0 T:15 8214 XUs and 20372 XVs -> 2624 XUVs
8426 XUVs total
1307 collection wire objects
8426 potential space points
Neighbour search...
1077636 tests to find 425152 neighbours
Iterating with no regularization...
Begin: 4.33353e+09
0 3.43293e+09
1 3.30447e+09
2 3.2958e+09
3 3.29424e+09
Now with regularization...
Begin: 3.24412e+09
0 3.24319e+09
05-Nov-2025 11:23:08 GMT Closed output file "np02vd_raw_run040361_0387_df-s04-d0_dw_0_20251105T021429_reco_stage1_20251105T112308_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 210.565 405.065 1989.13 313.173 375.748 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.8846e-05 0.000151695 0.000348586 0.000126578 6.23542e-05 23
produce:tpcrawdecoder:PDVDTPCReader 79.4953 136.71 227.808 124.746 41.8596 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.764692 1.00801 1.26315 1.00123 0.151302 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000403509 0.000557493 0.00104067 0.00052107 0.000146003 23
produce:ophit:OpHitFinder 0.000118323 0.00022216 0.000921884 0.000174769 0.000157178 23
produce:opflash:OpFlashFinderVerticalDrift 8.3838e-05 0.000147988 0.000548902 0.000124494 9.2887e-05 23
produce:wclsdatavd:WireCellToolkit 65.5027 89.97 183.026 80.339 27.7173 23
produce:gaushit:GausHitFinder 1.01187 1.69885 3.56515 1.54816 0.579315 23
produce:nhitsfilter:NumberOfHitsFilter 0.000261061 0.000460506 0.000796357 0.000438875 0.000142871 23
produce:reco3d:SpacePointSolver 8.62608 16.998 46.6088 14.1964 9.65234 23
produce:hitpdune:DisambigFromSpacePoints 0.146977 0.396924 1.96706 0.288007 0.370751 23
produce:pandora:StandardPandora 20.5672 149.542 1614.83 41.8485 344.303 23
produce:pandoraTrack:LArPandoraTrackCreation 0.691681 2.55044 10.4923 1.90428 2.45582 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0289318 0.050495 0.0930697 0.047898 0.0154957 23
[art]:TriggerResults:TriggerResultInserter 4.8e-05 7.44518e-05 0.000183084 7.1655e-05 2.65061e-05 23
end_path:out1:RootOutput 8.997e-06 1.71087e-05 5.6967e-05 1.5519e-05 9.5496e-06 23
end_path:out1:RootOutput(write) 4.52863 6.01184 7.97688 5.91936 0.729807 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7763.07 MB
Peak resident set size usage (VmHWM): 5820.23 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040361_0387_df-s04-d0_dw_0_20251105T021429_reco_stage1_20251105T112308_keepup.root
\tHists: np02vd_raw_run040361_0387_df-s04-d0_dw_0_20251105T021429_reco_stage1_20251105T112309_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040361_0387_df-s04-d0_dw_0_20251105T021429_reco_stage1_20251105T112308_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040361_0387_df-s04-d0_dw_0_20251105T021429_reco_stage1_20251105T112308_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": 1762341790.0,
"core.end_time": 1762341790.0,
"core.events": [
35848,
35852,
35856,
35860,
35864,
35868,
35872,
35876,
35880,
35884,
35888,
35892,
35896,
35900,
35904,
35908,
35912,
35916,
35920,
35924,
35928,
35932,
35936
],
"core.event_count": 23,
"core.first_event_number": 35848,
"core.last_event_number": 35936,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40361
],
"core.runs_subruns": [
4036100001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040361_0387_df-s04-d0_dw_0_20251105T021429.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040361_0387_df-s04-d0_dw_0_20251105T021429_reco_stage1_20251105T112309_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": 1762341790.0,
"core.end_time": 1762341790.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40361
],
"core.runs_subruns": [
4036100001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040361_0387_df-s04-d0_dw_0_20251105T021429.hdf5"
}
]
} 