Jobsub ID 242257.0@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[15:44:44.008] D [ glue ] frame sink sees EOS
[15:44:44.008] D [ pgraph ] <Pgrapher:> graph execution complete
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.35 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.98 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.76 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.34 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.24 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.89 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.77 sec
[15:44:44.008] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[15:44:44.008] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[15:44:44.008] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[15:44:44.008] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:44:44.008] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:44:44.008] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:44:44.008] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[15:44:44.008] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:44:44.008] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[15:44:44.008] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[15:44:44.008] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[15:44:44.008] I [ timer ] Timer: Total node execution : 50.26000038534403 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 35635 traces tagged "gauss"
FrameSaver: q=1.71119e+07 n=941165 tag=gauss
wclsFrameSaver: saving 43432 traces tagged "wiener"
FrameSaver: q=1.85421e+07 n=913971 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 184 XUs and 290 XVs -> 12 XUVs
C:0 T:1 706 XUs and 1025 XVs -> 28 XUVs
C:0 T:2 477 XUs and 491 XVs -> 22 XUVs
C:0 T:3 561 XUs and 634 XVs -> 33 XUVs
C:0 T:4 25616 XUs and 26508 XVs -> 2564 XUVs
C:0 T:5 21189 XUs and 27613 XVs -> 2785 XUVs
C:0 T:6 1161 XUs and 1399 XVs -> 25 XUVs
C:0 T:7 1225 XUs and 1508 XVs -> 104 XUVs
C:0 T:8 1 XUs and 3 XVs -> 0 XUVs
C:0 T:9 112 XUs and 272 XVs -> 4 XUVs
C:0 T:10 380 XUs and 485 XVs -> 35 XUVs
C:0 T:11 224 XUs and 564 XVs -> 26 XUVs
C:0 T:12 297 XUs and 360 XVs -> 14 XUVs
C:0 T:13 297 XUs and 507 XVs -> 18 XUVs
C:0 T:14 1315 XUs and 945 XVs -> 37 XUVs
C:0 T:15 467 XUs and 633 XVs -> 27 XUVs
5734 XUVs total
1710 collection wire objects
5734 potential space points
Neighbour search...
369212 tests to find 82562 neighbours
Iterating with no regularization...
Begin: 5.18383e+10
0 4.70069e+10
1 4.66234e+10
2 4.65969e+10
Now with regularization...
Begin: 4.58459e+10
0 4.58357e+10
TestBeamParticleCreationAlgorithm::SetupTestBeamVertex - Test beam particle has no initial vertex
03-Nov-2025 15:46:39 CET Closed output file "np02vd_raw_run040343_0159_df-s04-d1_dw_0_20251031T211610_reco_stage1_20251103T144639_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 58.9702 737.487 12774 96.8558 2565 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 4.4203e-05 7.31956e-05 0.000223741 6.2913e-05 3.67474e-05 24
produce:tpcrawdecoder:PDVDTPCReader 8.73758 10.6303 16.2891 10.4434 1.55047 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.301276 0.308788 0.340694 0.30537 0.00900236 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000194486 0.00031968 0.000789516 0.000310504 0.000110352 24
produce:ophit:OpHitFinder 4.1969e-05 9.29428e-05 0.00060557 6.73415e-05 0.000108455 24
produce:opflash:OpFlashFinderVerticalDrift 3.0838e-05 5.73683e-05 0.000325152 4.70235e-05 5.64098e-05 24
produce:wclsdatavd:WireCellToolkit 32.2146 77.2948 425.137 48.3132 90.6664 24
produce:gaushit:GausHitFinder 0.414957 1.21658 7.32354 0.770447 1.65018 24
produce:nhitsfilter:NumberOfHitsFilter 0.00011686 0.000230123 0.000696991 0.000189401 0.000146245 24
produce:reco3d:SpacePointSolver 3.15066 12.9114 89.4748 6.02189 22.5362 24
produce:hitpdune:DisambigFromSpacePoints 0.0497281 0.248275 1.61383 0.131707 0.411844 24
produce:pandora:StandardPandora 8.52146 629.839 12333.2 20.9084 2478.94 24
produce:pandoraTrack:LArPandoraTrackCreation 0.256082 0.903735 5.75651 0.510334 1.20732 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.00805325 0.0199977 0.0511486 0.018345 0.00919508 24
[art]:TriggerResults:TriggerResultInserter 1.1952e-05 2.07966e-05 8.0752e-05 1.8755e-05 1.29637e-05 24
end_path:out1:RootOutput 2.936e-06 7.27408e-06 2.9305e-05 6.031e-06 6.02038e-06 24
end_path:out1:RootOutput(write) 2.59094 4.08559 8.98954 4.05433 1.34239 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 12339.2 MB
Peak resident set size usage (VmHWM): 10293.9 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040343_0159_df-s04-d1_dw_0_20251031T211610_reco_stage1_20251103T144639_keepup.root
\tHists: np02vd_raw_run040343_0159_df-s04-d1_dw_0_20251031T211610_reco_stage1_20251103T144639_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040343_0159_df-s04-d1_dw_0_20251031T211610_reco_stage1_20251103T144639_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040343_0159_df-s04-d1_dw_0_20251031T211610_reco_stage1_20251103T144639_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": 1762181200.0,
"core.end_time": 1762181200.0,
"core.events": [
29357,
29365,
29373,
29381,
29389,
29397,
29405,
29413,
29421,
29429,
29437,
29445,
29453,
29461,
29469,
29477,
29485,
29493,
29501,
29509,
29517,
29525,
29533,
29541
],
"core.event_count": 24,
"core.first_event_number": 29357,
"core.last_event_number": 29541,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40343
],
"core.runs_subruns": [
4034300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040343_0159_df-s04-d1_dw_0_20251031T211610.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040343_0159_df-s04-d1_dw_0_20251031T211610_reco_stage1_20251103T144639_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": 1762181200.0,
"core.end_time": 1762181200.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40343
],
"core.runs_subruns": [
4034300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040343_0159_df-s04-d1_dw_0_20251031T211610.hdf5"
}
]
} 