Jobsub ID 236601.43@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[13:29:06.629] D [ glue ] frame sink sees EOS
[13:29:06.629] D [ pgraph ] <Pgrapher:> graph execution complete
[13:29:06.629] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.5 sec
[13:29:06.629] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.32 sec
[13:29:06.629] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.65 sec
[13:29:06.629] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.07 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.01 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.01 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.64 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.46 sec
[13:29:06.630] I [ timer ] Timer: WireCell::Aux::Resampler : 1.16 sec
[13:29:06.630] I [ timer ] Timer: WireCell::Aux::Resampler : 1.1 sec
[13:29:06.630] I [ timer ] Timer: WireCell::Aux::Resampler : 1.08 sec
[13:29:06.630] I [ timer ] Timer: WireCell::Aux::Resampler : 1.07 sec
[13:29:06.630] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:29:06.630] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:29:06.630] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:29:06.630] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[13:29:06.630] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:29:06.630] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:29:06.630] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:29:06.630] I [ timer ] Timer: Total node execution : 135.1299999319017 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 40859 traces tagged "gauss"
FrameSaver: q=1.12298e+07 n=915218 tag=gauss
wclsFrameSaver: saving 51658 traces tagged "wiener"
FrameSaver: q=1.2374e+07 n=886152 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 615 XUs and 688 XVs -> 30 XUVs
C:0 T:1 666 XUs and 641 XVs -> 34 XUVs
C:0 T:2 3072 XUs and 3510 XVs -> 174 XUVs
C:0 T:3 6167 XUs and 5661 XVs -> 531 XUVs
C:0 T:4 1940 XUs and 1877 XVs -> 109 XUVs
C:0 T:5 3393 XUs and 3861 XVs -> 269 XUVs
C:0 T:6 392 XUs and 352 XVs -> 40 XUVs
C:0 T:7 865 XUs and 932 XVs -> 60 XUVs
C:0 T:8 1214 XUs and 1038 XVs -> 66 XUVs
C:0 T:9 713 XUs and 673 XVs -> 110 XUVs
C:0 T:10 628 XUs and 457 XVs -> 38 XUVs
C:0 T:11 41 XUs and 68 XVs -> 3 XUVs
C:0 T:12 1154 XUs and 1131 XVs -> 146 XUVs
C:0 T:13 1206 XUs and 991 XVs -> 176 XUVs
C:0 T:14 364 XUs and 459 XVs -> 24 XUVs
C:0 T:15 883 XUs and 919 XVs -> 78 XUVs
1888 XUVs total
1155 collection wire objects
1888 potential space points
Neighbour search...
31212 tests to find 11734 neighbours
Iterating with no regularization...
Begin: 9.70696e+09
0 9.12267e+09
1 9.11027e+09
2 9.1099e+09
Now with regularization...
Begin: 8.79722e+09
0 8.79641e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 13:29:52 CET Closed output file "np02vd_raw_run040346_3307_df-s04-d0_dw_0_20251103T102239_reco_stage1_20251103T122952_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 174.161 245.364 404.02 225.979 58.0335 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.8613e-05 8.92547e-05 0.000230594 8.1943e-05 3.23788e-05 23
produce:tpcrawdecoder:PDVDTPCReader 41.9586 84.1881 172.094 73.8573 31.3706 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.580778 0.778171 1.73307 0.702682 0.250287 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000327591 0.000381886 0.000733798 0.000369262 7.65229e-05 23
produce:ophit:OpHitFinder 7.9279e-05 0.000113541 0.000605946 9.1217e-05 0.000105499 23
produce:opflash:OpFlashFinderVerticalDrift 5.7475e-05 8.05413e-05 0.000327591 6.5128e-05 5.40094e-05 23
produce:wclsdatavd:WireCellToolkit 76.8603 104.639 150.684 95.9201 24.298 23
produce:gaushit:GausHitFinder 0.777096 1.33191 2.0774 1.28129 0.31503 23
produce:nhitsfilter:NumberOfHitsFilter 0.000175292 0.000298005 0.000487769 0.000254741 9.11123e-05 23
produce:reco3d:SpacePointSolver 6.81935 13.374 23.106 12.7994 4.16271 23
produce:hitpdune:DisambigFromSpacePoints 0.0984313 0.205149 0.462263 0.191554 0.0792657 23
produce:pandora:StandardPandora 12.5178 33.6648 153.743 26.2848 28.2792 23
produce:pandoraTrack:LArPandoraTrackCreation 0.625724 1.55396 3.72999 1.20382 0.802126 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0248505 0.0370782 0.0596256 0.0352359 0.00978905 23
[art]:TriggerResults:TriggerResultInserter 3.6795e-05 4.71634e-05 0.000116563 4.5138e-05 1.57258e-05 23
end_path:out1:RootOutput 6.911e-06 9.53204e-06 3.1607e-05 8.442e-06 4.81285e-06 23
end_path:out1:RootOutput(write) 4.87628 5.49066 6.92612 5.38603 0.500341 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5951.53 MB
Peak resident set size usage (VmHWM): 3921.35 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3307_df-s04-d0_dw_0_20251103T102239_reco_stage1_20251103T122952_keepup.root
\tHists: np02vd_raw_run040346_3307_df-s04-d0_dw_0_20251103T102239_reco_stage1_20251103T122952_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3307_df-s04-d0_dw_0_20251103T102239_reco_stage1_20251103T122952_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3307_df-s04-d0_dw_0_20251103T102239_reco_stage1_20251103T122952_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_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": 1762172992.0,
"core.end_time": 1762172992.0,
"core.events": [
305248,
305252,
305256,
305260,
305264,
305268,
305272,
305276,
305280,
305284,
305288,
305292,
305296,
305300,
305304,
305308,
305312,
305316,
305320,
305324,
305328,
305332,
305336
],
"core.event_count": 23,
"core.first_event_number": 305248,
"core.last_event_number": 305336,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3307_df-s04-d0_dw_0_20251103T102239.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3307_df-s04-d0_dw_0_20251103T102239_reco_stage1_20251103T122952_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_11_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": 1762172992.0,
"core.end_time": 1762172992.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3307_df-s04-d0_dw_0_20251103T102239.hdf5"
}
]
} 