Jobsub ID 242425.38@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=47 anode=0
[18:52:09.026] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[18:52:09.026] D [ glue ] frame sink sees EOS
[18:52:09.026] D [ pgraph ] <Pgrapher:> graph execution complete
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.5 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.37 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.12 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.89 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.5 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.44 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.41 sec
[18:52:09.026] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[18:52:09.026] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[18:52:09.026] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[18:52:09.026] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[18:52:09.026] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:09.026] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[18:52:09.026] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:09.026] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[18:52:09.026] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[18:52:09.026] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[18:52:09.026] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[18:52:09.026] I [ timer ] Timer: Total node execution : 56.389999555423856 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 46761 traces tagged "gauss"
FrameSaver: q=1.21995e+07 n=946869 tag=gauss
wclsFrameSaver: saving 58700 traces tagged "wiener"
FrameSaver: q=1.32964e+07 n=905583 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 712 XUs and 1310 XVs -> 39 XUVs
C:0 T:1 1131 XUs and 1201 XVs -> 36 XUVs
C:0 T:2 1754 XUs and 1438 XVs -> 99 XUVs
C:0 T:3 696 XUs and 1207 XVs -> 70 XUVs
C:0 T:4 1840 XUs and 2701 XVs -> 154 XUVs
C:0 T:5 9007 XUs and 11676 XVs -> 905 XUVs
C:0 T:6 668 XUs and 1221 XVs -> 33 XUVs
C:0 T:7 1319 XUs and 1499 XVs -> 74 XUVs
C:0 T:8 833 XUs and 635 XVs -> 55 XUVs
C:0 T:9 1509 XUs and 1968 XVs -> 112 XUVs
C:0 T:10 405 XUs and 590 XVs -> 26 XUVs
C:0 T:11 613 XUs and 419 XVs -> 41 XUVs
C:0 T:12 832 XUs and 793 XVs -> 49 XUVs
C:0 T:13 1052 XUs and 1006 XVs -> 73 XUVs
C:0 T:14 530 XUs and 463 XVs -> 38 XUVs
C:0 T:15 740 XUs and 710 XVs -> 43 XUVs
1847 XUVs total
1111 collection wire objects
1847 potential space points
Neighbour search...
49139 tests to find 14902 neighbours
Iterating with no regularization...
Begin: 2.28284e+10
0 2.24206e+10
1 2.24029e+10
Now with regularization...
Begin: 2.17808e+10
0 2.17793e+10
03-Nov-2025 18:52:46 CET Closed output file "np02vd_raw_run040346_3587_df-s04-d3_dw_0_20251103T142749_reco_stage1_20251103T175246_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 83.9808 126.538 276.979 118.075 42.0375 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.1461e-05 0.000113545 0.000319474 0.000106551 4.82191e-05 24
produce:tpcrawdecoder:PDVDTPCReader 4.46033 5.05107 6.73729 4.85787 0.51286 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.0122428 0.0172703 0.0481434 0.0148884 0.00734261 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000329814 0.000418801 0.000671728 0.00040098 7.37829e-05 24
produce:ophit:OpHitFinder 6.7241e-05 0.000132116 0.000592048 0.000114342 9.91501e-05 24
produce:opflash:OpFlashFinderVerticalDrift 5.2521e-05 8.55423e-05 0.000316184 7.5241e-05 5.05201e-05 24
produce:wclsdatavd:WireCellToolkit 53.0459 67.1168 95.0133 60.6977 13.2709 24
produce:gaushit:GausHitFinder 0.737634 1.26375 1.73909 1.27632 0.273417 24
produce:nhitsfilter:NumberOfHitsFilter 0.000156932 0.000283307 0.000439116 0.000290853 6.25114e-05 24
produce:reco3d:SpacePointSolver 5.84034 11.4481 19.5814 10.9062 2.91109 24
produce:hitpdune:DisambigFromSpacePoints 0.0993856 0.228692 0.606096 0.21048 0.0982071 24
produce:pandora:StandardPandora 10.5173 34.9856 152.655 26.836 29.9423 24
produce:pandoraTrack:LArPandoraTrackCreation 0.515253 1.04861 2.08449 0.934026 0.378479 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0170018 0.0384945 0.0566729 0.0378615 0.0114914 24
[art]:TriggerResults:TriggerResultInserter 1.9091e-05 3.35689e-05 0.000106131 2.71205e-05 1.82527e-05 24
end_path:out1:RootOutput 3.61e-06 8.44842e-06 3.8211e-05 7.25e-06 6.36676e-06 24
end_path:out1:RootOutput(write) 4.02353 5.28628 7.2642 5.26091 0.661716 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5749.09 MB
Peak resident set size usage (VmHWM): 3665.13 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3587_df-s04-d3_dw_0_20251103T142749_reco_stage1_20251103T175246_keepup.root
\tHists: np02vd_raw_run040346_3587_df-s04-d3_dw_0_20251103T142749_reco_stage1_20251103T175246_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3587_df-s04-d3_dw_0_20251103T142749_reco_stage1_20251103T175246_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3587_df-s04-d3_dw_0_20251103T142749_reco_stage1_20251103T175246_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": 1762192367.0,
"core.end_time": 1762192367.0,
"core.events": [
330939,
330943,
330947,
330951,
330955,
330959,
330963,
330967,
330971,
330975,
330979,
330983,
330987,
330991,
330995,
330999,
331003,
331007,
331011,
331015,
331019,
331023,
331027,
331031
],
"core.event_count": 24,
"core.first_event_number": 330939,
"core.last_event_number": 331031,
"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_3587_df-s04-d3_dw_0_20251103T142749.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3587_df-s04-d3_dw_0_20251103T142749_reco_stage1_20251103T175246_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": 1762192367.0,
"core.end_time": 1762192367.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_3587_df-s04-d3_dw_0_20251103T142749.hdf5"
}
]
} 