Jobsub ID 41697.81@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
=95
[09:59:37.360] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=95 anode=0
[09:59:37.360] D [ glue ] <FrameFanin:nfsp> EOS at call=95 with 8
[09:59:37.360] D [ glue ] frame sink sees EOS
[09:59:37.360] D [ pgraph ] <Pgrapher:> graph execution complete
[09:59:37.360] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.33 sec
[09:59:37.360] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.48 sec
[09:59:37.360] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.39 sec
[09:59:37.360] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.38 sec
[09:59:37.361] I [ timer ] Timer: WireCell::Aux::Resampler : 1.87 sec
[09:59:37.361] I [ timer ] Timer: WireCell::Aux::Resampler : 1.83 sec
[09:59:37.361] I [ timer ] Timer: WireCell::Aux::Resampler : 1.81 sec
[09:59:37.361] I [ timer ] Timer: WireCell::Aux::Resampler : 1.8 sec
[09:59:37.361] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[09:59:37.361] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[09:59:37.361] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[09:59:37.361] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[09:59:37.361] I [ timer ] Timer: Total node execution : 67.92999986559153 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 15814 traces tagged "gauss"
FrameSaver: q=1.86626e+06 n=334579 tag=gauss
wclsFrameSaver: saving 19266 traces tagged "wiener"
FrameSaver: q=1.94435e+06 n=322001 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 151 XUs and 307 XVs -> 5 XUVs
C:0 T:1 278 XUs and 392 XVs -> 9 XUVs
C:0 T:2 383 XUs and 473 XVs -> 25 XUVs
C:0 T:3 536 XUs and 802 XVs -> 36 XUVs
C:0 T:4 193 XUs and 259 XVs -> 22 XUVs
C:0 T:5 392 XUs and 373 XVs -> 9 XUVs
C:0 T:6 510 XUs and 622 XVs -> 10 XUVs
C:0 T:7 1729 XUs and 2057 XVs -> 96 XUVs
212 XUVs total
181 collection wire objects
212 potential space points
Neighbour search...
1332 tests to find 766 neighbours
Iterating with no regularization...
Begin: 2.06624e+07
0 2.02741e+07
1 2.02723e+07
Now with regularization...
Begin: 1.99636e+07
0 1.9963e+07
18-Sep-2025 09:59:59 BST Closed output file "np02vd_raw_run039433_0363_df-s05-d0_dw_0_20250918T061615_reco_stage1_20250918T085959_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 74.7714 149.948 1700.48 112.469 227.703 48
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.8916e-05 0.000110634 0.000265963 0.000102179 3.20699e-05 48
produce:tpcrawdecoder:PDVDTPCReader 12.6547 14.2223 18.182 13.8004 1.28381 48
produce:triggerrawdecoder:PDVDTriggerReader4 0.300067 0.3103 0.410222 0.30349 0.0180456 48
produce:pdvddaphne:DAPHNEReaderPDVD 5.66097 6.44789 7.49665 6.45065 0.321683 48
produce:ophit:OpHitFinder 0.0372504 0.0578784 0.0733187 0.0592331 0.00687891 48
produce:opflash:OpFlashFinderVerticalDrift 0.00741367 0.0170513 0.034046 0.0157505 0.00555514 48
produce:wclsdatavd:WireCellToolkit 43.6796 61.5554 93.2407 56.5464 12.8153 48
produce:gaushit:GausHitFinder 0.399871 0.951235 2.08501 0.895176 0.322099 48
produce:nhitsfilter:NumberOfHitsFilter 7.2893e-05 0.000179684 0.000463113 0.000170575 6.81919e-05 48
produce:reco3d:SpacePointSolver 1.80251 6.05078 27.0133 5.35865 3.87211 48
produce:hitpdune:DisambigFromSpacePoints 0.0342079 0.14261 0.840004 0.0900302 0.128561 48
produce:pandora:StandardPandora 4.08965 56.1803 1560.43 17.7941 219.938 48
produce:pandoraTrack:LArPandoraTrackCreation 0.116575 0.550287 3.56608 0.401185 0.526599 48
produce:pandoraGnocalo:GnocchiCalorimetry 0.00734167 0.0229611 0.0591727 0.0213152 0.0092072 48
[art]:TriggerResults:TriggerResultInserter 1.9059e-05 3.34501e-05 0.000100731 3.3083e-05 1.12857e-05 48
end_path:out1:RootOutput 3.876e-06 8.90981e-06 2.7499e-05 8.838e-06 4.39162e-06 48
end_path:out1:RootOutput(write) 2.49332 3.41222 4.68052 3.36653 0.461101 48
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4196.92 MB
Peak resident set size usage (VmHWM): 2305.09 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0363_df-s05-d0_dw_0_20250918T061615_reco_stage1_20250918T085959_keepup.root
\tHists: np02vd_raw_run039433_0363_df-s05-d0_dw_0_20250918T061615_reco_stage1_20250918T090001_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0363_df-s05-d0_dw_0_20250918T061615_reco_stage1_20250918T085959_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0363_df-s05-d0_dw_0_20250918T061615_reco_stage1_20250918T085959_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": 1758186001.0,
"core.end_time": 1758186001.0,
"core.events": [
104208,
104214,
104220,
104226,
104232,
104238,
104244,
104250,
104256,
104262,
104268,
104274,
104280,
104286,
104292,
104298,
104304,
104310,
104316,
104322,
104328,
104334,
104340,
104346,
104352,
104358,
104364,
104370,
104376,
104382,
104388,
104394,
104400,
104406,
104412,
104418,
104424,
104430,
104436,
104442,
104448,
104454,
104460,
104466,
104472,
104478,
104484,
104490
],
"core.event_count": 48,
"core.first_event_number": 104208,
"core.last_event_number": 104490,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0363_df-s05-d0_dw_0_20250918T061615.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0363_df-s05-d0_dw_0_20250918T061615_reco_stage1_20250918T090001_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": 1758186001.0,
"core.end_time": 1758186001.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0363_df-s05-d0_dw_0_20250918T061615.hdf5"
}
]
} 