Jobsub ID 242278.137@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.58 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.24 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.92 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.75 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.74 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.61 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.59 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.08 sec
[12:39:37.208] I [ timer ] Timer: WireCell::Aux::Resampler : 0.44 sec
[12:39:37.208] I [ timer ] Timer: WireCell::Aux::Resampler : 0.43 sec
[12:39:37.208] I [ timer ] Timer: WireCell::Aux::Resampler : 0.43 sec
[12:39:37.208] I [ timer ] Timer: WireCell::Aux::Resampler : 0.43 sec
[12:39:37.208] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:39:37.208] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:39:37.208] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:39:37.208] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:39:37.208] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:39:37.208] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:39:37.208] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:39:37.208] I [ timer ] Timer: Total node execution : 73.2999992929399 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 56439 traces tagged "gauss"
FrameSaver: q=1.60017e+07 n=1217227 tag=gauss
wclsFrameSaver: saving 69919 traces tagged "wiener"
FrameSaver: q=1.722e+07 n=1167727 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 2512 XUs and 2004 XVs -> 65 XUVs
C:0 T:1 1813 XUs and 935 XVs -> 40 XUVs
C:0 T:2 394 XUs and 321 XVs -> 13 XUVs
C:0 T:3 702 XUs and 913 XVs -> 23 XUVs
C:0 T:4 4692 XUs and 6324 XVs -> 233 XUVs
C:0 T:5 183 XUs and 212 XVs -> 7 XUVs
C:0 T:6 29164 XUs and 40315 XVs -> 2674 XUVs
C:0 T:7 895 XUs and 1215 XVs -> 31 XUVs
C:0 T:8 333 XUs and 380 XVs -> 22 XUVs
C:0 T:9 1489 XUs and 1632 XVs -> 82 XUVs
C:0 T:10 1710 XUs and 2499 XVs -> 116 XUVs
C:0 T:11 1233 XUs and 1515 XVs -> 57 XUVs
C:0 T:12 691 XUs and 1267 XVs -> 42 XUVs
C:0 T:13 950 XUs and 1030 XVs -> 60 XUVs
C:0 T:14 550 XUs and 669 XVs -> 34 XUVs
C:0 T:15 189 XUs and 249 XVs -> 15 XUVs
3514 XUVs total
1881 collection wire objects
3514 potential space points
Neighbour search...
150698 tests to find 31436 neighbours
Iterating with no regularization...
Begin: 2.72071e+10
0 2.5796e+10
1 2.57449e+10
2 2.57442e+10
Now with regularization...
Begin: 2.55852e+10
0 2.55848e+10
TestBeamParticleCreationAlgorithm::SetupTestBeamVertex - Test beam particle has no initial vertex
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 12:40:41 CET Closed output file "np02vd_raw_run040346_3253_df-s04-d0_dw_0_20251103T093537_reco_stage1_20251103T114041_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 88.4704 139.312 396.354 129.979 63.8627 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.9001e-05 0.000106609 0.000242606 9.8996e-05 3.9125e-05 23
produce:tpcrawdecoder:PDVDTPCReader 9.57076 11.4535 14.7785 11.2829 1.2499 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.303098 0.351205 1.06947 0.314919 0.153996 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000305505 0.000361562 0.000703043 0.00034007 8.26885e-05 23
produce:ophit:OpHitFinder 6.4581e-05 0.000123703 0.000521692 9.8756e-05 9.04255e-05 23
produce:opflash:OpFlashFinderVerticalDrift 4.76e-05 7.12402e-05 0.000330973 5.6938e-05 5.65737e-05 23
produce:wclsdatavd:WireCellToolkit 43.7879 63.1367 82.5316 61.7113 13.9061 23
produce:gaushit:GausHitFinder 0.835107 1.20311 1.63952 1.14032 0.192157 23
produce:nhitsfilter:NumberOfHitsFilter 0.000216718 0.000278622 0.000465616 0.000271641 4.86444e-05 23
produce:reco3d:SpacePointSolver 7.42931 11.0477 15.7662 10.8423 2.37214 23
produce:hitpdune:DisambigFromSpacePoints 0.107964 0.207145 0.483769 0.209858 0.0812929 23
produce:pandora:StandardPandora 13.1192 46.3778 299.18 25.8891 57.6657 23
produce:pandoraTrack:LArPandoraTrackCreation 0.415533 0.918903 1.96048 0.908125 0.317364 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0248529 0.0340719 0.0506259 0.0331503 0.0068777 23
[art]:TriggerResults:TriggerResultInserter 1.5639e-05 2.35067e-05 5.3992e-05 2.126e-05 8.63793e-06 23
end_path:out1:RootOutput 4.198e-06 1.0679e-05 2.2172e-05 1.035e-05 4.25075e-06 23
end_path:out1:RootOutput(write) 4.05099 4.5509 5.40052 4.5692 0.288921 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6259.27 MB
Peak resident set size usage (VmHWM): 4220.61 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3253_df-s04-d0_dw_0_20251103T093537_reco_stage1_20251103T114041_keepup.root
\tHists: np02vd_raw_run040346_3253_df-s04-d0_dw_0_20251103T093537_reco_stage1_20251103T114041_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3253_df-s04-d0_dw_0_20251103T093537_reco_stage1_20251103T114041_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3253_df-s04-d0_dw_0_20251103T093537_reco_stage1_20251103T114041_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": 1762170041.0,
"core.end_time": 1762170041.0,
"core.events": [
300256,
300260,
300264,
300268,
300272,
300276,
300280,
300284,
300288,
300292,
300296,
300300,
300304,
300308,
300312,
300316,
300320,
300324,
300328,
300332,
300336,
300340,
300344
],
"core.event_count": 23,
"core.first_event_number": 300256,
"core.last_event_number": 300344,
"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_3253_df-s04-d0_dw_0_20251103T093537.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3253_df-s04-d0_dw_0_20251103T093537_reco_stage1_20251103T114041_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": 1762170041.0,
"core.end_time": 1762170041.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_3253_df-s04-d0_dw_0_20251103T093537.hdf5"
}
]
} 