Jobsub ID 49050.87@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
c ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0
[01:53:57.590] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[01:53:57.590] D [ glue ] frame sink sees EOS
[01:53:57.590] D [ pgraph ] <Pgrapher:> graph execution complete
[01:53:57.590] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.35 sec
[01:53:57.590] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 18.91 sec
[01:53:57.590] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 18.37 sec
[01:53:57.590] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 18.3 sec
[01:53:57.590] I [ timer ] Timer: WireCell::Aux::Resampler : 0.61 sec
[01:53:57.590] I [ timer ] Timer: WireCell::Aux::Resampler : 0.6 sec
[01:53:57.591] I [ timer ] Timer: WireCell::Aux::Resampler : 0.6 sec
[01:53:57.591] I [ timer ] Timer: WireCell::Aux::Resampler : 0.6 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[01:53:57.591] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:53:57.591] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[01:53:57.591] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[01:53:57.591] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[01:53:57.591] I [ timer ] Timer: Total node execution : 77.36000039055943 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 24864 traces tagged "gauss"
FrameSaver: q=5.41343e+06 n=604384 tag=gauss
wclsFrameSaver: saving 30776 traces tagged "wiener"
FrameSaver: q=5.65804e+06 n=583476 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 852 XUs and 1095 XVs -> 48 XUVs
C:0 T:1 968 XUs and 998 XVs -> 31 XUVs
C:0 T:2 1279 XUs and 1248 XVs -> 67 XUVs
C:0 T:3 2080 XUs and 1739 XVs -> 111 XUVs
C:0 T:4 1691 XUs and 2002 XVs -> 56 XUVs
C:0 T:5 1050 XUs and 1097 XVs -> 26 XUVs
C:0 T:6 234 XUs and 346 XVs -> 9 XUVs
C:0 T:7 4088 XUs and 6102 XVs -> 317 XUVs
665 XUVs total
516 collection wire objects
665 potential space points
Neighbour search...
8443 tests to find 3502 neighbours
Iterating with no regularization...
Begin: 8.69543e+09
0 8.35608e+09
1 8.35265e+09
Now with regularization...
Begin: 8.26458e+09
0 8.26454e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 01:54:35 BST Closed output file "np02vd_raw_run039499_0061_df-s05-d3_dw_0_20250918T194636_reco_stage1_20250919T005435_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 94.6458 305.312 4589.74 123.608 796.427 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.3606e-05 0.000147043 0.000342895 0.000140715 4.66117e-05 41
produce:tpcrawdecoder:PDVDTPCReader 24.3143 41.2517 53.6952 41.8263 6.29304 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.384719 0.488555 2.06255 0.427037 0.2581 41
produce:pdvddaphne:DAPHNEReaderPDVD 5.92302 9.29145 12.8253 9.56031 1.70755 41
produce:ophit:OpHitFinder 0.0387724 0.0591647 0.119024 0.056157 0.01358 41
produce:opflash:OpFlashFinderVerticalDrift 0.00643132 0.014854 0.038346 0.0139235 0.00613022 41
produce:wclsdatavd:WireCellToolkit 33.7872 47.3776 87.2011 40.4504 15.3388 41
produce:gaushit:GausHitFinder 0.466844 0.868266 3.07002 0.749032 0.473449 41
produce:nhitsfilter:NumberOfHitsFilter 0.000118723 0.000312756 0.00387314 0.000190698 0.000571463 41
produce:reco3d:SpacePointSolver 3.11257 8.36664 58.1471 5.96106 10.3411 41
produce:hitpdune:DisambigFromSpacePoints 0.0357498 0.168682 2.22909 0.0873163 0.373389 41
produce:pandora:StandardPandora 7.93425 193.495 4382.86 22.0942 779.588 41
produce:pandoraTrack:LArPandoraTrackCreation 0.135213 0.554923 6.25049 0.322182 1.07366 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.0119434 0.0214355 0.0605135 0.0188748 0.00969628 41
[art]:TriggerResults:TriggerResultInserter 2.2683e-05 4.36075e-05 0.000114896 4.0446e-05 1.57484e-05 41
end_path:out1:RootOutput 4.759e-06 1.1938e-05 3.4956e-05 1.1632e-05 6.59958e-06 41
end_path:out1:RootOutput(write) 2.63883 3.32677 4.56303 3.23382 0.394934 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5771.58 MB
Peak resident set size usage (VmHWM): 3851.26 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0061_df-s05-d3_dw_0_20250918T194636_reco_stage1_20250919T005435_keepup.root
\tHists: np02vd_raw_run039499_0061_df-s05-d3_dw_0_20250918T194636_reco_stage1_20250919T005435_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0061_df-s05-d3_dw_0_20250918T194636_reco_stage1_20250919T005435_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0061_df-s05-d3_dw_0_20250918T194636_reco_stage1_20250919T005435_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": 1758243276.0,
"core.end_time": 1758243276.0,
"core.events": [
14949,
14955,
14961,
14967,
14973,
14979,
14985,
14991,
14997,
15003,
15009,
15015,
15021,
15027,
15033,
15039,
15045,
15051,
15057,
15063,
15069,
15075,
15081,
15087,
15093,
15099,
15105,
15111,
15117,
15123,
15129,
15135,
15141,
15147,
15153,
15159,
15165,
15171,
15177,
15183,
15189
],
"core.event_count": 41,
"core.first_event_number": 14949,
"core.last_event_number": 15189,
"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_0061_df-s05-d3_dw_0_20250918T194636.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0061_df-s05-d3_dw_0_20250918T194636_reco_stage1_20250919T005435_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": 1758243276.0,
"core.end_time": 1758243276.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_0061_df-s05-d3_dw_0_20250918T194636.hdf5"
}
]
} 