Jobsub ID 241758.66@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
81] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[23:04:17.081] D [ glue ] frame sink sees EOS
[23:04:17.081] D [ pgraph ] <Pgrapher:> graph execution complete
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 24.02 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.03 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.03 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.15 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.74 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.64 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.62 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.41 sec
[23:04:17.082] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[23:04:17.082] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[23:04:17.082] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[23:04:17.082] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[23:04:17.082] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[23:04:17.082] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:04:17.082] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:04:17.082] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:04:17.082] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[23:04:17.082] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[23:04:17.082] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[23:04:17.082] I [ timer ] Timer: Total node execution : 102.63999938033521 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 43970 traces tagged "gauss"
FrameSaver: q=2.4468e+07 n=1186869 tag=gauss
wclsFrameSaver: saving 56927 traces tagged "wiener"
FrameSaver: q=2.61428e+07 n=1138422 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 4875 XUs and 8493 XVs -> 448 XUVs
C:0 T:1 36287 XUs and 52074 XVs -> 2511 XUVs
C:0 T:2 2237 XUs and 3737 XVs -> 113 XUVs
C:0 T:3 2363 XUs and 2503 XVs -> 155 XUVs
C:0 T:4 91 XUs and 154 XVs -> 4 XUVs
C:0 T:5 464 XUs and 812 XVs -> 53 XUVs
C:0 T:6 65 XUs and 72 XVs -> 0 XUVs
C:0 T:7 302 XUs and 278 XVs -> 6 XUVs
C:0 T:8 663 XUs and 814 XVs -> 50 XUVs
C:0 T:9 1091 XUs and 902 XVs -> 35 XUVs
C:0 T:10 1096 XUs and 1108 XVs -> 40 XUVs
C:0 T:11 1630 XUs and 1775 XVs -> 80 XUVs
C:0 T:12 327 XUs and 543 XVs -> 44 XUVs
C:0 T:13 1050 XUs and 1326 XVs -> 80 XUVs
C:0 T:14 194 XUs and 223 XVs -> 18 XUVs
C:0 T:15 572 XUs and 554 XVs -> 54 XUVs
3691 XUVs total
2060 collection wire objects
3691 potential space points
Neighbour search...
108699 tests to find 28258 neighbours
Iterating with no regularization...
Begin: 2.94157e+11
0 2.84442e+11
1 2.8326e+11
2 2.83248e+11
Now with regularization...
Begin: 2.82718e+11
0 2.82717e+11
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
01-Nov-2025 23:05:27 CET Closed output file "np02vd_raw_run040346_0523_df-s04-d2_dw_0_20251101T190931_reco_stage1_20251101T220527_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 171.055 232.266 313.742 222.039 42.9114 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 3.7479e-05 4.54102e-05 9.3109e-05 4.1423e-05 1.17973e-05 24
produce:tpcrawdecoder:PDVDTPCReader 48.267 89.4281 123.713 88.253 16.9851 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.533309 0.715938 1.30969 0.684562 0.165446 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000358369 0.00039721 0.000640217 0.000380951 5.49281e-05 24
produce:ophit:OpHitFinder 4.8503e-05 7.18512e-05 0.000445253 5.13455e-05 7.84538e-05 24
produce:opflash:OpFlashFinderVerticalDrift 3.974e-05 5.26348e-05 0.000263997 4.1756e-05 4.44972e-05 24
produce:wclsdatavd:WireCellToolkit 63.0184 84.2592 127.833 70.4383 22.315 24
produce:gaushit:GausHitFinder 0.849461 1.32617 1.93231 1.28383 0.265142 24
produce:nhitsfilter:NumberOfHitsFilter 0.000137474 0.0002496 0.00043664 0.000225876 7.67351e-05 24
produce:reco3d:SpacePointSolver 6.41395 12.6413 18.4756 11.9678 3.47367 24
produce:hitpdune:DisambigFromSpacePoints 0.0767375 0.216202 0.442901 0.203439 0.0906942 24
produce:pandora:StandardPandora 14.512 38.2373 69.1515 36.7999 16.5624 24
produce:pandoraTrack:LArPandoraTrackCreation 0.379306 1.14114 2.07841 0.995091 0.499331 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0181696 0.0303892 0.045404 0.0301839 0.00699701 24
[art]:TriggerResults:TriggerResultInserter 1.3323e-05 1.5916e-05 4.3377e-05 1.484e-05 5.76281e-06 24
end_path:out1:RootOutput 2.789e-06 6.42629e-06 1.7432e-05 6.087e-06 3.0502e-06 24
end_path:out1:RootOutput(write) 3.84851 4.24157 4.66394 4.19872 0.249837 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5171.3 MB
Peak resident set size usage (VmHWM): 3152.31 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_0523_df-s04-d2_dw_0_20251101T190931_reco_stage1_20251101T220527_keepup.root
\tHists: np02vd_raw_run040346_0523_df-s04-d2_dw_0_20251101T190931_reco_stage1_20251101T220527_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_0523_df-s04-d2_dw_0_20251101T190931_reco_stage1_20251101T220527_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_0523_df-s04-d2_dw_0_20251101T190931_reco_stage1_20251101T220527_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": 1762034727.0,
"core.end_time": 1762034727.0,
"core.events": [
48230,
48234,
48238,
48242,
48246,
48250,
48254,
48258,
48262,
48266,
48270,
48274,
48278,
48282,
48286,
48290,
48294,
48298,
48302,
48306,
48310,
48314,
48318,
48322
],
"core.event_count": 24,
"core.first_event_number": 48230,
"core.last_event_number": 48322,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_0523_df-s04-d2_dw_0_20251101T190931.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_0523_df-s04-d2_dw_0_20251101T190931_reco_stage1_20251101T220527_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": 1762034727.0,
"core.end_time": 1762034727.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_0523_df-s04-d2_dw_0_20251101T190931.hdf5"
}
]
} 