Jobsub ID 42777.54@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
[04:53:34.942] D [ glue ] <ChannelSelector:chsel0> see EOS at call=40
[04:53:34.942] D [ aux ] <Resampler:resmp0> EOS at call=81
[04:53:34.942] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0
[04:53:34.942] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[04:53:34.942] D [ glue ] frame sink sees EOS
[04:53:34.942] D [ pgraph ] <Pgrapher:> graph execution complete
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.78 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.96 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.69 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.12 sec
[04:53:34.942] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[04:53:34.942] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[04:53:34.942] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[04:53:34.942] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[04:53:34.942] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[04:53:34.942] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.01 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[04:53:34.942] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[04:53:34.942] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:53:34.942] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[04:53:34.942] I [ timer ] Timer: Total node execution : 33.489999698475 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 18280 traces tagged "gauss"
FrameSaver: q=3.38272e+06 n=448941 tag=gauss
wclsFrameSaver: saving 23026 traces tagged "wiener"
FrameSaver: q=3.60509e+06 n=435039 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 9351 XUs and 10072 XVs -> 671 XUVs
C:0 T:1 2662 XUs and 2489 XVs -> 136 XUVs
C:0 T:2 530 XUs and 824 XVs -> 43 XUVs
C:0 T:3 463 XUs and 318 XVs -> 5 XUVs
C:0 T:4 321 XUs and 227 XVs -> 9 XUVs
C:0 T:5 1053 XUs and 1025 XVs -> 41 XUVs
C:0 T:6 359 XUs and 322 XVs -> 12 XUVs
C:0 T:7 32 XUs and 32 XVs -> 0 XUVs
917 XUVs total
662 collection wire objects
917 potential space points
Neighbour search...
23445 tests to find 5614 neighbours
Iterating with no regularization...
Begin: 2.07838e+09
0 2.02773e+09
1 2.02596e+09
Now with regularization...
Begin: 1.98207e+09
0 1.98203e+09
19-Sep-2025 04:54:01 UTC Closed output file "np02vd_raw_run039499_0181_df-s05-d5_dw_0_20250918T234526_reco_stage1_20250919T045401_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 48.0749 129.082 1197.23 82.0316 207.746 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.6502e-05 0.000149026 0.00033367 0.000129024 5.65239e-05 41
produce:tpcrawdecoder:PDVDTPCReader 7.21498 7.97343 12.3424 7.69121 0.875764 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.388065 0.399027 0.5462 0.392562 0.0244263 41
produce:pdvddaphne:DAPHNEReaderPDVD 2.76508 3.58182 4.20078 3.61894 0.339322 41
produce:ophit:OpHitFinder 0.0281099 0.0493997 0.0822444 0.04733 0.0104367 41
produce:opflash:OpFlashFinderVerticalDrift 0.00405818 0.0131294 0.0244959 0.012 0.0047596 41
produce:wclsdatavd:WireCellToolkit 26.9426 40.1443 91.7174 34.5182 12.717 41
produce:gaushit:GausHitFinder 0.286075 0.671149 1.88684 0.6604 0.253259 41
produce:nhitsfilter:NumberOfHitsFilter 9.5974e-05 0.000380127 0.000756904 0.000332141 0.000205977 41
produce:reco3d:SpacePointSolver 2.2122 6.71885 23.0564 5.53727 4.04355 41
produce:hitpdune:DisambigFromSpacePoints 0.0208239 0.0971465 0.413504 0.0750364 0.0773898 41
produce:pandora:StandardPandora 4.43637 66.3954 1116.97 16.8574 199.022 41
produce:pandoraTrack:LArPandoraTrackCreation 0.0714673 0.374377 1.35147 0.33055 0.255384 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.00879055 0.01871 0.0506247 0.0170965 0.0088202 41
[art]:TriggerResults:TriggerResultInserter 1.7051e-05 4.09964e-05 0.000104193 3.5941e-05 1.8711e-05 41
end_path:out1:RootOutput 4.4e-06 1.07965e-05 3.6472e-05 9.931e-06 5.87009e-06 41
end_path:out1:RootOutput(write) 2.07964 2.60744 3.39899 2.46316 0.349253 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6666.26 MB
Peak resident set size usage (VmHWM): 4427.79 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0181_df-s05-d5_dw_0_20250918T234526_reco_stage1_20250919T045401_keepup.root
\tHists: np02vd_raw_run039499_0181_df-s05-d5_dw_0_20250918T234526_reco_stage1_20250919T045401_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0181_df-s05-d5_dw_0_20250918T234526_reco_stage1_20250919T045401_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0181_df-s05-d5_dw_0_20250918T234526_reco_stage1_20250919T045401_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": 1758257643.0,
"core.end_time": 1758257643.0,
"core.events": [
44165,
44171,
44177,
44183,
44189,
44195,
44201,
44207,
44213,
44219,
44225,
44231,
44237,
44243,
44249,
44255,
44261,
44267,
44273,
44279,
44285,
44291,
44297,
44303,
44309,
44315,
44321,
44327,
44333,
44339,
44345,
44351,
44357,
44363,
44369,
44375,
44381,
44387,
44393,
44399,
44405
],
"core.event_count": 41,
"core.first_event_number": 44165,
"core.last_event_number": 44405,
"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_0181_df-s05-d5_dw_0_20250918T234526.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0181_df-s05-d5_dw_0_20250918T234526_reco_stage1_20250919T045401_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": 1758257643.0,
"core.end_time": 1758257643.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_0181_df-s05-d5_dw_0_20250918T234526.hdf5"
}
]
} 