Jobsub ID 42777.49@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
ler:resmp0> EOS at call=81
[05:31:19.595] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0
[05:31:19.595] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[05:31:19.595] D [ glue ] frame sink sees EOS
[05:31:19.595] D [ pgraph ] <Pgrapher:> graph execution complete
[05:31:19.595] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.21 sec
[05:31:19.595] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.04 sec
[05:31:19.595] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.73 sec
[05:31:19.595] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.29 sec
[05:31:19.595] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[05:31:19.595] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[05:31:19.595] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[05:31:19.595] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[05:31:19.595] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[05:31:19.595] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[05:31:19.595] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[05:31:19.595] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:31:19.595] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:31:19.598] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:31:19.599] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[05:31:19.599] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[05:31:19.599] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[05:31:19.599] I [ timer ] Timer: Total node execution : 32.449999986216426 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 30135 traces tagged "gauss"
FrameSaver: q=4.48592e+06 n=688539 tag=gauss
wclsFrameSaver: saving 37791 traces tagged "wiener"
FrameSaver: q=4.78555e+06 n=666609 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 860 XUs and 1075 XVs -> 32 XUVs
C:0 T:1 2098 XUs and 3067 XVs -> 233 XUVs
C:0 T:2 1500 XUs and 1897 XVs -> 73 XUVs
C:0 T:3 1005 XUs and 1299 XVs -> 43 XUVs
C:0 T:4 8510 XUs and 11749 XVs -> 731 XUVs
C:0 T:5 1098 XUs and 788 XVs -> 31 XUVs
C:0 T:6 3892 XUs and 5452 XVs -> 235 XUVs
C:0 T:7 3436 XUs and 4154 XVs -> 213 XUVs
1591 XUVs total
969 collection wire objects
1591 potential space points
Neighbour search...
39159 tests to find 11390 neighbours
Iterating with no regularization...
Begin: 1.66276e+09
0 1.60744e+09
1 1.60511e+09
2 1.60505e+09
Now with regularization...
Begin: 1.58511e+09
0 1.58508e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 05:32:15 BST Closed output file "np02vd_raw_run039499_0179_df-s04-d1_dw_0_20250918T234115_reco_stage1_20250919T043215_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 56.4065 102.265 208.567 96.7141 33.5967 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.012e-05 0.000124366 0.00028734 0.000104948 4.47053e-05 41
produce:tpcrawdecoder:PDVDTPCReader 10.1055 12.5971 14.4626 12.559 1.0454 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.376192 0.403733 0.841887 0.383468 0.0835391 41
produce:pdvddaphne:DAPHNEReaderPDVD 3.7122 4.18403 4.90775 4.1799 0.22238 41
produce:ophit:OpHitFinder 0.0494215 0.0586436 0.0788784 0.0575815 0.00558161 41
produce:opflash:OpFlashFinderVerticalDrift 0.00905856 0.0144009 0.0561004 0.0128491 0.00697918 41
produce:wclsdatavd:WireCellToolkit 27.1837 39.8061 66.0473 35.9598 9.99965 41
produce:gaushit:GausHitFinder 0.469669 0.857541 1.26137 0.825802 0.212099 41
produce:nhitsfilter:NumberOfHitsFilter 0.000123351 0.000193799 0.00040429 0.000179648 5.0203e-05 41
produce:reco3d:SpacePointSolver 2.39234 7.63929 14.2838 7.09347 2.93447 41
produce:hitpdune:DisambigFromSpacePoints 0.0302408 0.122423 0.389406 0.1016 0.0808101 41
produce:pandora:StandardPandora 5.98793 32.9094 133.553 21.8701 26.5015 41
produce:pandoraTrack:LArPandoraTrackCreation 0.122883 0.445046 1.10263 0.3953 0.225068 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.00965969 0.0220315 0.0344409 0.021643 0.00488559 41
[art]:TriggerResults:TriggerResultInserter 2.6099e-05 3.73164e-05 7.2927e-05 3.5517e-05 8.7838e-06 41
end_path:out1:RootOutput 5.179e-06 1.19356e-05 3.3714e-05 1.1361e-05 5.08538e-06 41
end_path:out1:RootOutput(write) 2.63391 3.18705 3.68354 3.16999 0.217718 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4339.74 MB
Peak resident set size usage (VmHWM): 2431.93 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0179_df-s04-d1_dw_0_20250918T234115_reco_stage1_20250919T043215_keepup.root
\tHists: np02vd_raw_run039499_0179_df-s04-d1_dw_0_20250918T234115_reco_stage1_20250919T043216_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0179_df-s04-d1_dw_0_20250918T234115_reco_stage1_20250919T043215_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0179_df-s04-d1_dw_0_20250918T234115_reco_stage1_20250919T043215_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": 1758256336.0,
"core.end_time": 1758256336.0,
"core.events": [
43638,
43644,
43650,
43656,
43662,
43668,
43674,
43680,
43686,
43692,
43698,
43704,
43710,
43716,
43722,
43728,
43734,
43740,
43746,
43752,
43758,
43764,
43770,
43776,
43782,
43788,
43794,
43800,
43806,
43812,
43818,
43824,
43830,
43836,
43842,
43848,
43854,
43860,
43866,
43872,
43878
],
"core.event_count": 41,
"core.first_event_number": 43638,
"core.last_event_number": 43878,
"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_0179_df-s04-d1_dw_0_20250918T234115.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0179_df-s04-d1_dw_0_20250918T234115_reco_stage1_20250919T043216_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": 1758256336.0,
"core.end_time": 1758256336.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_0179_df-s04-d1_dw_0_20250918T234115.hdf5"
}
]
} 