Jobsub ID 49050.88@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
l=81
[00:26:39.456] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0
[00:26:39.456] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[00:26:39.456] D [ glue ] frame sink sees EOS
[00:26:39.456] D [ pgraph ] <Pgrapher:> graph execution complete
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.84 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.81 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.73 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.36 sec
[00:26:39.456] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[00:26:39.456] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[00:26:39.456] I [ timer ] Timer: WireCell::Aux::Resampler : 0.18 sec
[00:26:39.456] I [ timer ] Timer: WireCell::Aux::Resampler : 0.17 sec
[00:26:39.456] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[00:26:39.456] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[00:26:39.456] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[00:26:39.456] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:26:39.456] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[00:26:39.456] I [ timer ] Timer: Total node execution : 23.490000251680613 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 21306 traces tagged "gauss"
FrameSaver: q=4.05881e+06 n=533202 tag=gauss
wclsFrameSaver: saving 27087 traces tagged "wiener"
FrameSaver: q=4.46488e+06 n=520885 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1192 XUs and 1499 XVs -> 123 XUVs
C:0 T:1 145 XUs and 145 XVs -> 7 XUVs
C:0 T:2 15758 XUs and 22066 XVs -> 1489 XUVs
C:0 T:3 461 XUs and 1029 XVs -> 63 XUVs
C:0 T:4 597 XUs and 802 XVs -> 33 XUVs
C:0 T:5 1051 XUs and 1188 XVs -> 38 XUVs
C:0 T:6 1309 XUs and 1450 XVs -> 52 XUVs
C:0 T:7 870 XUs and 808 XVs -> 31 XUVs
1836 XUVs total
880 collection wire objects
1836 potential space points
Neighbour search...
67560 tests to find 16528 neighbours
Iterating with no regularization...
Begin: 9.79484e+09
0 9.7041e+09
1 9.69739e+09
Now with regularization...
Begin: 9.6486e+09
0 9.64838e+09
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
19-Sep-2025 00:27:06 BST Closed output file "np02vd_raw_run039499_0061_df-s05-d5_dw_0_20250918T194559_reco_stage1_20250918T232706_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 69.9058 177.918 2262.59 95.0188 349.183 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.0041e-05 9.70985e-05 0.00029704 8.7433e-05 3.5144e-05 41
produce:tpcrawdecoder:PDVDTPCReader 18.7057 34.5794 52.4975 32.4381 8.35149 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.311298 0.389613 0.620812 0.368782 0.0765034 41
produce:pdvddaphne:DAPHNEReaderPDVD 3.97712 7.20694 10.8647 7.05016 1.93866 41
produce:ophit:OpHitFinder 0.025646 0.0401642 0.0539874 0.0395956 0.00598657 41
produce:opflash:OpFlashFinderVerticalDrift 0.0046865 0.0098544 0.0179352 0.00934801 0.00238805 41
produce:wclsdatavd:WireCellToolkit 21.0413 30.8828 52.8059 26.3181 9.02956 41
produce:gaushit:GausHitFinder 0.274651 0.623861 1.85108 0.587536 0.283044 41
produce:nhitsfilter:NumberOfHitsFilter 8.1914e-05 0.000155157 0.000441168 0.000141054 6.17365e-05 41
produce:reco3d:SpacePointSolver 1.93217 6.58982 35.5112 5.08879 5.69051 41
produce:hitpdune:DisambigFromSpacePoints 0.0196717 0.11359 1.29837 0.0645051 0.202227 41
produce:pandora:StandardPandora 4.23951 94.678 2127.19 14.7847 339.688 41
produce:pandoraTrack:LArPandoraTrackCreation 0.0890429 0.406217 3.21307 0.251681 0.529112 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.00577423 0.0152586 0.0399333 0.0143796 0.00637913 41
[art]:TriggerResults:TriggerResultInserter 1.4822e-05 3.64119e-05 8.9817e-05 3.1978e-05 1.43827e-05 41
end_path:out1:RootOutput 4.988e-06 8.42032e-06 3.1578e-05 7.431e-06 4.2448e-06 41
end_path:out1:RootOutput(write) 1.66312 2.35672 3.24097 2.3556 0.299531 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5997.26 MB
Peak resident set size usage (VmHWM): 4004.68 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0061_df-s05-d5_dw_0_20250918T194559_reco_stage1_20250918T232706_keepup.root
\tHists: np02vd_raw_run039499_0061_df-s05-d5_dw_0_20250918T194559_reco_stage1_20250918T232706_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0061_df-s05-d5_dw_0_20250918T194559_reco_stage1_20250918T232706_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0061_df-s05-d5_dw_0_20250918T194559_reco_stage1_20250918T232706_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": 1758238027.0,
"core.end_time": 1758238027.0,
"core.events": [
14879,
14885,
14891,
14897,
14903,
14909,
14915,
14921,
14927,
14933,
14939,
14945,
14951,
14957,
14963,
14969,
14975,
14981,
14987,
14993,
14999,
15005,
15011,
15017,
15023,
15029,
15035,
15041,
15047,
15053,
15059,
15065,
15071,
15077,
15083,
15089,
15095,
15101,
15107,
15113,
15119
],
"core.event_count": 41,
"core.first_event_number": 14879,
"core.last_event_number": 15119,
"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-d5_dw_0_20250918T194559.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0061_df-s05-d5_dw_0_20250918T194559_reco_stage1_20250918T232706_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": 1758238027.0,
"core.end_time": 1758238027.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-d5_dw_0_20250918T194559.hdf5"
}
]
} 