Jobsub ID 41739.126@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
:21.620] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=93 anode=0
[12:01:21.620] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[12:01:21.620] D [ glue ] frame sink sees EOS
[12:01:21.620] D [ pgraph ] <Pgrapher:> graph execution complete
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 35.92 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.41 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.33 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.94 sec
[12:01:21.620] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[12:01:21.620] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[12:01:21.620] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[12:01:21.620] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[12:01:21.620] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[12:01:21.620] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:01:21.620] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:01:21.620] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:01:21.620] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:01:21.620] I [ timer ] Timer: Total node execution : 70.95999755337834 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 29135 traces tagged "gauss"
FrameSaver: q=2.49318e+07 n=1041923 tag=gauss
wclsFrameSaver: saving 38731 traces tagged "wiener"
FrameSaver: q=2.59424e+07 n=1007150 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 9481 XUs and 13984 XVs -> 710 XUVs
C:0 T:1 61263 XUs and 133857 XVs -> 5726 XUVs
C:0 T:2 261 XUs and 228 XVs -> 17 XUVs
C:0 T:3 2518 XUs and 3130 XVs -> 85 XUVs
C:0 T:4 78 XUs and 76 XVs -> 2 XUVs
C:0 T:5 1069 XUs and 1530 XVs -> 91 XUVs
C:0 T:6 1518 XUs and 1160 XVs -> 85 XUVs
C:0 T:7 628 XUs and 923 XVs -> 26 XUVs
6742 XUVs total
3347 collection wire objects
6742 potential space points
Neighbour search...
222394 tests to find 49352 neighbours
Iterating with no regularization...
Begin: 9.29167e+11
0 9.09095e+11
1 9.08343e+11
Now with regularization...
Begin: 9.0683e+11
0 9.06811e+11
18-Sep-2025 12:02:53 CEST Closed output file "np02vd_raw_run039433_0393_df-s05-d2_dw_0_20250918T072937_reco_stage1_20250918T100253_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 81.9238 131.447 661.317 114.36 90.8202 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.1949e-05 7.46323e-05 0.00029496 6.309e-05 4.21967e-05 47
produce:tpcrawdecoder:PDVDTPCReader 27.6895 40.5529 55.9986 39.9653 7.21129 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.301193 0.419858 1.15115 0.387147 0.162086 47
produce:pdvddaphne:DAPHNEReaderPDVD 6.05022 9.71239 19.9333 9.45268 2.35829 47
produce:ophit:OpHitFinder 0.028974 0.0384971 0.047037 0.040034 0.00462393 47
produce:opflash:OpFlashFinderVerticalDrift 0.0044607 0.0084699 0.0189529 0.00805466 0.00275126 47
produce:wclsdatavd:WireCellToolkit 24.4138 43.554 89.9154 44.1281 12.2921 47
produce:gaushit:GausHitFinder 0.297721 0.563596 1.28098 0.545987 0.220257 47
produce:nhitsfilter:NumberOfHitsFilter 7.0754e-05 0.000129613 0.000321131 0.000112043 5.87237e-05 47
produce:reco3d:SpacePointSolver 1.43931 4.79802 14.33 4.34805 2.59144 47
produce:hitpdune:DisambigFromSpacePoints 0.0245424 0.0675215 0.422917 0.0507328 0.0689942 47
produce:pandora:StandardPandora 3.89745 29.3424 533.17 11.1764 84.036 47
produce:pandoraTrack:LArPandoraTrackCreation 0.0878409 0.264251 1.36283 0.191312 0.238618 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00682746 0.0133055 0.034345 0.0132537 0.00493398 47
[art]:TriggerResults:TriggerResultInserter 1.3055e-05 1.95476e-05 4.9854e-05 1.7753e-05 6.07067e-06 47
end_path:out1:RootOutput 3.517e-06 4.9863e-06 2.3846e-05 4.248e-06 3.37866e-06 47
end_path:out1:RootOutput(write) 1.7541 2.09436 2.72677 2.02344 0.249246 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5787.92 MB
Peak resident set size usage (VmHWM): 3906.3 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0393_df-s05-d2_dw_0_20250918T072937_reco_stage1_20250918T100253_keepup.root
\tHists: np02vd_raw_run039433_0393_df-s05-d2_dw_0_20250918T072937_reco_stage1_20250918T100254_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0393_df-s05-d2_dw_0_20250918T072937_reco_stage1_20250918T100253_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0393_df-s05-d2_dw_0_20250918T072937_reco_stage1_20250918T100253_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": 1758189774.0,
"core.end_time": 1758189774.0,
"core.events": [
112748,
112754,
112760,
112766,
112772,
112778,
112784,
112790,
112796,
112802,
112808,
112814,
112820,
112826,
112832,
112838,
112844,
112850,
112856,
112862,
112868,
112874,
112880,
112886,
112892,
112898,
112904,
112910,
112916,
112922,
112928,
112934,
112940,
112946,
112952,
112958,
112964,
112970,
112976,
112982,
112988,
112994,
113000,
113006,
113012,
113018,
113024
],
"core.event_count": 47,
"core.first_event_number": 112748,
"core.last_event_number": 113024,
"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_0393_df-s05-d2_dw_0_20250918T072937.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0393_df-s05-d2_dw_0_20250918T072937_reco_stage1_20250918T100254_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": 1758189774.0,
"core.end_time": 1758189774.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_0393_df-s05-d2_dw_0_20250918T072937.hdf5"
}
]
} 