Jobsub ID 236147.17@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
8.636] D [ glue ] <FrameFanin:nfsp> EOS at call=43 with 8
[00:36:48.636] D [ glue ] frame sink sees EOS
[00:36:48.636] D [ pgraph ] <Pgrapher:> graph execution complete
[00:36:48.636] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.66 sec
[00:36:48.636] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.5 sec
[00:36:48.636] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.44 sec
[00:36:48.636] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.36 sec
[00:36:48.636] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.36 sec
[00:36:48.636] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.02 sec
[00:36:48.636] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.78 sec
[00:36:48.636] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.41 sec
[00:36:48.636] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[00:36:48.636] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[00:36:48.636] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[00:36:48.636] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[00:36:48.636] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[00:36:48.636] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:36:48.637] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:36:48.637] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:36:48.637] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:36:48.637] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[00:36:48.637] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:36:48.637] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:36:48.637] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:36:48.637] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:36:48.637] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:36:48.637] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[00:36:48.637] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[00:36:48.637] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[00:36:48.637] I [ timer ] Timer: Total node execution : 58.6200002040714 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 44432 traces tagged "gauss"
FrameSaver: q=1.05516e+07 n=921670 tag=gauss
wclsFrameSaver: saving 55508 traces tagged "wiener"
FrameSaver: q=1.12698e+07 n=878987 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 722 XUs and 779 XVs -> 31 XUVs
C:0 T:1 289 XUs and 313 XVs -> 17 XUVs
C:0 T:2 1507 XUs and 1565 XVs -> 52 XUVs
C:0 T:3 425 XUs and 486 XVs -> 20 XUVs
C:0 T:4 20468 XUs and 17262 XVs -> 1324 XUVs
C:0 T:5 131 XUs and 145 XVs -> 2 XUVs
C:0 T:6 2931 XUs and 2230 XVs -> 225 XUVs
C:0 T:7 1147 XUs and 1434 XVs -> 42 XUVs
C:0 T:8 1166 XUs and 1002 XVs -> 76 XUVs
C:0 T:9 633 XUs and 643 XVs -> 62 XUVs
C:0 T:10 634 XUs and 755 XVs -> 61 XUVs
C:0 T:11 815 XUs and 1136 XVs -> 124 XUVs
C:0 T:12 885 XUs and 899 XVs -> 45 XUVs
C:0 T:13 613 XUs and 1097 XVs -> 47 XUVs
C:0 T:14 2681 XUs and 3306 XVs -> 219 XUVs
C:0 T:15 1805 XUs and 1626 XVs -> 161 XUVs
2508 XUVs total
1558 collection wire objects
2508 potential space points
Neighbour search...
78806 tests to find 24524 neighbours
Iterating with no regularization...
Begin: 5.23392e+09
0 4.48346e+09
1 4.33671e+09
2 4.33559e+09
Now with regularization...
Begin: 4.2812e+09
0 4.28065e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Oct-2025 00:37:27 CEST Closed output file "np02vd_raw_run040140_0933_df-s03-d1_dw_0_20251018T181431_reco_stage1_20251018T223727_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 83.0694 160.484 527.187 142.215 87.6538 22
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.3051e-05 0.000144033 0.000670857 0.000123801 0.000121985 22
produce:tpcrawdecoder:PDVDTPCReader 5.89961 6.59827 7.52897 6.44585 0.441121 22
produce:triggerrawdecoder:PDVDTriggerReader4 0.00831216 0.0156595 0.0659858 0.0102374 0.0140166 22
produce:pdvddaphne:DAPHNEReaderPDVD 2.18007 2.50663 2.90785 2.4864 0.185237 22
produce:ophit:OpHitFinder 0.0343405 0.0404581 0.0498382 0.0401393 0.00326705 22
produce:opflash:OpFlashFinderVerticalDrift 0.000673286 0.00467268 0.0163918 0.00395493 0.00395788 22
produce:wclsdatavd:WireCellToolkit 52.9063 78.3338 119.819 69.349 20.8497 22
produce:gaushit:GausHitFinder 0.612736 1.34509 2.64719 1.22999 0.451247 22
produce:nhitsfilter:NumberOfHitsFilter 0.000182312 0.000332288 0.000649776 0.000308213 0.000115675 22
produce:reco3d:SpacePointSolver 4.73277 13.3341 30.7443 12.5 5.48056 22
produce:hitpdune:DisambigFromSpacePoints 0.111714 0.276104 0.818955 0.24689 0.157867 22
produce:pandora:StandardPandora 10.6176 50.6469 351.916 35.7721 68.2283 22
produce:pandoraTrack:LArPandoraTrackCreation 0.284459 1.24414 3.75569 1.15812 0.749124 22
produce:pandoraGnocalo:GnocchiCalorimetry 0.0133392 0.03922 0.0687712 0.0410745 0.0132727 22
[art]:TriggerResults:TriggerResultInserter 1.757e-05 2.88485e-05 8.9191e-05 2.583e-05 1.37826e-05 22
end_path:out1:RootOutput 5.93e-06 1.05532e-05 3.3841e-05 9.785e-06 5.31006e-06 22
end_path:out1:RootOutput(write) 4.64721 6.0474 7.54146 5.99771 0.666529 22
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6298.04 MB
Peak resident set size usage (VmHWM): 4377.37 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040140_0933_df-s03-d1_dw_0_20251018T181431_reco_stage1_20251018T223727_keepup.root
\tHists: np02vd_raw_run040140_0933_df-s03-d1_dw_0_20251018T181431_reco_stage1_20251018T223728_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040140_0933_df-s03-d1_dw_0_20251018T181431_reco_stage1_20251018T223727_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040140_0933_df-s03-d1_dw_0_20251018T181431_reco_stage1_20251018T223727_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": 1760827048.0,
"core.end_time": 1760827048.0,
"core.events": [
164017,
164025,
164033,
164041,
164049,
164057,
164065,
164073,
164081,
164089,
164097,
164105,
164113,
164121,
164129,
164137,
164145,
164153,
164161,
164169,
164177,
164185
],
"core.event_count": 22,
"core.first_event_number": 164017,
"core.last_event_number": 164185,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40140
],
"core.runs_subruns": [
4014000001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040140_0933_df-s03-d1_dw_0_20251018T181431.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040140_0933_df-s03-d1_dw_0_20251018T181431_reco_stage1_20251018T223728_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": 1760827048.0,
"core.end_time": 1760827048.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40140
],
"core.runs_subruns": [
4014000001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040140_0933_df-s03-d1_dw_0_20251018T181431.hdf5"
}
]
} 