Jobsub ID 242251.87@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
anode0sigproc0> EOS at call=47 anode=0
[11:30:22.027] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[11:30:22.027] D [ glue ] frame sink sees EOS
[11:30:22.027] D [ pgraph ] <Pgrapher:> graph execution complete
[11:30:22.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 23.07 sec
[11:30:22.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 22.42 sec
[11:30:22.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 22.3 sec
[11:30:22.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 21.93 sec
[11:30:22.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 21.06 sec
[11:30:22.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 20.17 sec
[11:30:22.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 20.02 sec
[11:30:22.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.16 sec
[11:30:22.027] I [ timer ] Timer: WireCell::Aux::Resampler : 0.76 sec
[11:30:22.027] I [ timer ] Timer: WireCell::Aux::Resampler : 0.72 sec
[11:30:22.027] I [ timer ] Timer: WireCell::Aux::Resampler : 0.71 sec
[11:30:22.027] I [ timer ] Timer: WireCell::Aux::Resampler : 0.7 sec
[11:30:22.027] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[11:30:22.027] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[11:30:22.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:30:22.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:30:22.028] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:30:22.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:22.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:22.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:22.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:22.028] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:30:22.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:30:22.028] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:30:22.028] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:30:22.028] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:30:22.028] I [ timer ] Timer: Total node execution : 173.10999914444983 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 53015 traces tagged "gauss"
FrameSaver: q=1.38118e+07 n=1107900 tag=gauss
wclsFrameSaver: saving 64501 traces tagged "wiener"
FrameSaver: q=1.4679e+07 n=1060496 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 370 XUs and 1017 XVs -> 34 XUVs
C:0 T:1 189 XUs and 192 XVs -> 1 XUVs
C:0 T:2 1225 XUs and 1382 XVs -> 63 XUVs
C:0 T:3 1070 XUs and 1173 XVs -> 33 XUVs
C:0 T:4 381 XUs and 578 XVs -> 12 XUVs
C:0 T:5 1495 XUs and 1833 XVs -> 121 XUVs
C:0 T:6 2407 XUs and 2330 XVs -> 85 XUVs
C:0 T:7 7031 XUs and 9205 XVs -> 506 XUVs
C:0 T:8 1875 XUs and 1778 XVs -> 129 XUVs
C:0 T:9 1760 XUs and 1419 XVs -> 97 XUVs
C:0 T:10 257 XUs and 253 XVs -> 21 XUVs
C:0 T:11 758 XUs and 504 XVs -> 55 XUVs
C:0 T:12 88 XUs and 134 XVs -> 6 XUVs
C:0 T:13 715 XUs and 612 XVs -> 42 XUVs
C:0 T:14 3054 XUs and 2429 XVs -> 138 XUVs
C:0 T:15 1591 XUs and 1804 XVs -> 62 XUVs
1405 XUVs total
1080 collection wire objects
1405 potential space points
Neighbour search...
22903 tests to find 7490 neighbours
Iterating with no regularization...
Begin: 1.25608e+10
0 1.19593e+10
1 1.19213e+10
2 1.19212e+10
Now with regularization...
Begin: 1.16942e+10
0 1.16937e+10
03-Nov-2025 11:31:47 GMT Closed output file "np02vd_raw_run040346_3069_df-s04-d2_dw_0_20251103T070057_reco_stage1_20251103T113147_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 172.685 425.489 3180.67 284.049 600.832 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 0.000111039 0.000141131 0.000372251 0.000123773 5.55505e-05 24
produce:tpcrawdecoder:PDVDTPCReader 17.6876 18.9396 22.5801 18.4953 1.24115 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.691406 0.730089 0.949663 0.720255 0.0505364 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000568611 0.000709283 0.00158697 0.000620112 0.000227573 24
produce:ophit:OpHitFinder 8.9549e-05 0.000176368 0.000940181 0.000113754 0.000167379 24
produce:opflash:OpFlashFinderVerticalDrift 7.1685e-05 0.000144008 0.000598187 8.90325e-05 0.000116084 24
produce:wclsdatavd:WireCellToolkit 102.37 144.348 229.776 124.4 40.0516 24
produce:gaushit:GausHitFinder 1.39679 2.55964 5.72189 2.26124 0.895157 24
produce:nhitsfilter:NumberOfHitsFilter 0.000703094 0.0011224 0.00186441 0.00101884 0.0003531 24
produce:reco3d:SpacePointSolver 12.3026 24.796 79.4954 21.1661 13.3108 24
produce:hitpdune:DisambigFromSpacePoints 0.172647 0.504261 1.98302 0.38187 0.362293 24
produce:pandora:StandardPandora 26.1284 220.306 2847.4 61.9066 568.224 24
produce:pandoraTrack:LArPandoraTrackCreation 1.63792 5.43358 32.7343 4.02336 6.03336 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0526731 0.0836077 0.174963 0.0774074 0.027361 24
[art]:TriggerResults:TriggerResultInserter 4.3532e-05 0.000104672 0.000276922 9.7719e-05 4.08073e-05 24
end_path:out1:RootOutput 5.801e-06 2.07182e-05 7.7255e-05 1.7949e-05 1.41067e-05 24
end_path:out1:RootOutput(write) 6.67023 7.61345 9.29259 7.747 0.610326 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6963.37 MB
Peak resident set size usage (VmHWM): 4868.15 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3069_df-s04-d2_dw_0_20251103T070057_reco_stage1_20251103T113147_keepup.root
\tHists: np02vd_raw_run040346_3069_df-s04-d2_dw_0_20251103T070057_reco_stage1_20251103T113147_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3069_df-s04-d2_dw_0_20251103T070057_reco_stage1_20251103T113147_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3069_df-s04-d2_dw_0_20251103T070057_reco_stage1_20251103T113147_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": 1762169509.0,
"core.end_time": 1762169509.0,
"core.events": [
283386,
283390,
283394,
283398,
283402,
283406,
283410,
283414,
283418,
283422,
283426,
283430,
283434,
283438,
283442,
283446,
283450,
283454,
283458,
283462,
283466,
283470,
283474,
283478
],
"core.event_count": 24,
"core.first_event_number": 283386,
"core.last_event_number": 283478,
"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_3069_df-s04-d2_dw_0_20251103T070057.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3069_df-s04-d2_dw_0_20251103T070057_reco_stage1_20251103T113147_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": 1762169509.0,
"core.end_time": 1762169509.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_3069_df-s04-d2_dw_0_20251103T070057.hdf5"
}
]
} 