Jobsub ID 42563.97@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
<Resampler:resmp0> EOS at call=81
[00:42:09.421] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0
[00:42:09.421] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[00:42:09.421] D [ glue ] frame sink sees EOS
[00:42:09.421] D [ pgraph ] <Pgrapher:> graph execution complete
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.42 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.96 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.88 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.13 sec
[00:42:09.421] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[00:42:09.421] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[00:42:09.421] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[00:42:09.421] I [ timer ] Timer: WireCell::Aux::Resampler : 0.2 sec
[00:42:09.421] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:42:09.421] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[00:42:09.421] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[00:42:09.421] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[00:42:09.421] I [ timer ] Timer: Total node execution : 31.49000034108758 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 26541 traces tagged "gauss"
FrameSaver: q=4.49665e+06 n=588585 tag=gauss
wclsFrameSaver: saving 33667 traces tagged "wiener"
FrameSaver: q=4.75591e+06 n=562579 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1082 XUs and 1580 XVs -> 84 XUVs
C:0 T:1 1821 XUs and 2585 XVs -> 87 XUVs
C:0 T:2 628 XUs and 527 XVs -> 20 XUVs
C:0 T:3 15142 XUs and 17231 XVs -> 1163 XUVs
C:0 T:4 1957 XUs and 1779 XVs -> 164 XUVs
C:0 T:5 429 XUs and 435 XVs -> 15 XUVs
C:0 T:6 717 XUs and 636 XVs -> 27 XUVs
C:0 T:7 1034 XUs and 898 XVs -> 47 XUVs
1607 XUVs total
1062 collection wire objects
1607 potential space points
Neighbour search...
45911 tests to find 13074 neighbours
Iterating with no regularization...
Begin: 3.00981e+09
0 2.89095e+09
1 2.87653e+09
2 2.87618e+09
Now with regularization...
Begin: 2.83233e+09
0 2.83225e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 00:42:40 CEST Closed output file "np02vd_raw_run039499_0046_df-s05-d4_dw_0_20250918T191516_reco_stage1_20250918T224240_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 71.8015 171.23 1040.96 147.175 146.378 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.7121e-05 8.98835e-05 0.000386682 7.9743e-05 5.11667e-05 41
produce:tpcrawdecoder:PDVDTPCReader 9.86921 63.5465 95.1672 62.1583 15.7428 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.614191 0.721815 1.46784 0.663111 0.156448 41
produce:pdvddaphne:DAPHNEReaderPDVD 3.38221 12.3261 18.8973 11.9527 3.62681 41
produce:ophit:OpHitFinder 0.0262544 0.044159 0.0678968 0.0447284 0.00882996 41
produce:opflash:OpFlashFinderVerticalDrift 0.00440403 0.0138379 0.043626 0.0124307 0.00687904 41
produce:wclsdatavd:WireCellToolkit 27.1296 37.9167 86.0972 32.5487 12.1292 41
produce:gaushit:GausHitFinder 0.233673 0.631801 2.42713 0.535099 0.346284 41
produce:nhitsfilter:NumberOfHitsFilter 6.1072e-05 0.000331694 0.00101892 0.000297639 0.000216985 41
produce:reco3d:SpacePointSolver 1.40903 5.95177 15.2069 5.32866 2.95455 41
produce:hitpdune:DisambigFromSpacePoints 0.0120893 0.0823937 0.358849 0.057833 0.0738442 41
produce:pandora:StandardPandora 2.98109 47.4352 862.606 13.6268 134.772 41
produce:pandoraTrack:LArPandoraTrackCreation 0.0462334 0.394819 2.50659 0.27921 0.429569 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.00602939 0.0154364 0.0371184 0.0142975 0.00580019 41
[art]:TriggerResults:TriggerResultInserter 1.239e-05 2.42777e-05 0.000101333 1.931e-05 1.70167e-05 41
end_path:out1:RootOutput 3.53e-06 5.84488e-06 2.8301e-05 4.57e-06 4.52308e-06 41
end_path:out1:RootOutput(write) 1.8031 2.11993 2.7951 2.07767 0.187191 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7481.56 MB
Peak resident set size usage (VmHWM): 5566.52 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0046_df-s05-d4_dw_0_20250918T191516_reco_stage1_20250918T224240_keepup.root
\tHists: np02vd_raw_run039499_0046_df-s05-d4_dw_0_20250918T191516_reco_stage1_20250918T224240_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0046_df-s05-d4_dw_0_20250918T191516_reco_stage1_20250918T224240_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0046_df-s05-d4_dw_0_20250918T191516_reco_stage1_20250918T224240_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": 1758235361.0,
"core.end_time": 1758235361.0,
"core.events": [
11212,
11218,
11224,
11230,
11236,
11242,
11248,
11254,
11260,
11266,
11272,
11278,
11284,
11290,
11296,
11302,
11308,
11314,
11320,
11326,
11332,
11338,
11344,
11350,
11356,
11362,
11368,
11374,
11380,
11386,
11392,
11398,
11404,
11410,
11416,
11422,
11428,
11434,
11440,
11446,
11452
],
"core.event_count": 41,
"core.first_event_number": 11212,
"core.last_event_number": 11452,
"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_0046_df-s05-d4_dw_0_20250918T191516.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0046_df-s05-d4_dw_0_20250918T191516_reco_stage1_20250918T224240_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": 1758235361.0,
"core.end_time": 1758235361.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_0046_df-s05-d4_dw_0_20250918T191516.hdf5"
}
]
} 