Jobsub ID 236577.60@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
15] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[11:19:08.415] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[11:19:08.415] D [ glue ] frame sink sees EOS
[11:19:08.415] D [ pgraph ] <Pgrapher:> graph execution complete
[11:19:08.415] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 44.7 sec
[11:19:08.415] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 37.29 sec
[11:19:08.415] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.19 sec
[11:19:08.415] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.66 sec
[11:19:08.415] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.6 sec
[11:19:08.415] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.27 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.21 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.68 sec
[11:19:08.416] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[11:19:08.416] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[11:19:08.416] I [ timer ] Timer: WireCell::Aux::Resampler : 0.48 sec
[11:19:08.416] I [ timer ] Timer: WireCell::Aux::Resampler : 0.47 sec
[11:19:08.416] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:19:08.416] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:19:08.416] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:19:08.416] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:19:08.416] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:19:08.416] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:19:08.416] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:19:08.416] I [ timer ] Timer: Total node execution : 158.6000022944063 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 77753 traces tagged "gauss"
FrameSaver: q=8.228e+08 n=3251172 tag=gauss
wclsFrameSaver: saving 98628 traces tagged "wiener"
FrameSaver: q=8.28645e+08 n=3180203 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 7386 XUs and 4351 XVs -> 1805 XUVs
C:0 T:1 8630 XUs and 6148 XVs -> 1891 XUVs
C:0 T:2 66198 XUs and 53502 XVs -> 6479 XUVs
C:0 T:3 82324 XUs and 87493 XVs -> 5799 XUVs
C:0 T:4 293 XUs and 389 XVs -> 13 XUVs
C:0 T:5 285 XUs and 183 XVs -> 3 XUVs
C:0 T:6 1158 XUs and 1268 XVs -> 50 XUVs
C:0 T:7 792 XUs and 559 XVs -> 33 XUVs
C:0 T:8 925 XUs and 1108 XVs -> 54 XUVs
C:0 T:9 785 XUs and 1068 XVs -> 30 XUVs
C:0 T:10 14479 XUs and 91462 XVs -> 1597 XUVs
C:0 T:11 2097 XUs and 3563 XVs -> 118 XUVs
C:0 T:12 264 XUs and 607 XVs -> 23 XUVs
C:0 T:13 419 XUs and 911 XVs -> 34 XUVs
C:0 T:14 650 XUs and 907 XVs -> 48 XUVs
C:0 T:15 135 XUs and 158 XVs -> 7 XUVs
17984 XUVs total
5543 collection wire objects
17984 potential space points
Neighbour search...
1371166 tests to find 501078 neighbours
Iterating with no regularization...
Begin: 3.82353e+14
0 3.823e+14
Now with regularization...
Begin: 3.82292e+14
0 3.8229e+14
03-Nov-2025 11:41:33 CET Closed output file "np02vd_raw_run040346_3111_df-s04-d0_dw_0_20251103T073346_reco_stage1_20251103T104133_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 109.046 233.035 1518.72 170.032 280.489 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.16e-05 8.05702e-05 0.000171069 7.303e-05 2.27779e-05 23
produce:tpcrawdecoder:PDVDTPCReader 12.9125 13.9279 16.1755 13.6506 0.828383 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.459191 0.496049 0.544714 0.490444 0.0222423 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000366339 0.000417583 0.000926856 0.000395529 0.000109654 23
produce:ophit:OpHitFinder 6.123e-05 0.000105617 0.000720417 7.87e-05 0.000131925 23
produce:opflash:OpFlashFinderVerticalDrift 5.0369e-05 7.26515e-05 0.000379539 5.83e-05 6.57973e-05 23
produce:wclsdatavd:WireCellToolkit 66.1082 95.6912 189.458 93.2792 29.8024 23
produce:gaushit:GausHitFinder 0.800885 1.66781 7.39874 1.39329 1.30556 23
produce:nhitsfilter:NumberOfHitsFilter 0.000208349 0.000363953 0.00118706 0.000317889 0.000194941 23
produce:reco3d:SpacePointSolver 7.27298 14.6723 42.3046 13.6355 7.19033 22
produce:hitpdune:DisambigFromSpacePoints 0.093433 0.265805 1.31146 0.195425 0.247144 22
produce:pandora:StandardPandora 12.7623 102.493 1281.05 32.6457 261.263 22
produce:pandoraTrack:LArPandoraTrackCreation 0.492213 1.45229 6.44827 1.11593 1.21574 22
produce:pandoraGnocalo:GnocchiCalorimetry 0.0216859 0.037388 0.0630389 0.0374187 0.00952127 22
[art]:TriggerResults:TriggerResultInserter 2.104e-05 2.85769e-05 6.971e-05 2.342e-05 1.33883e-05 23
end_path:out1:RootOutput 5.29e-06 1.04433e-05 3.076e-05 9.52e-06 4.71403e-06 23
end_path:out1:RootOutput(write) 5.28763 7.45867 9.97602 7.01996 1.22577 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 8129.83 MB
Peak resident set size usage (VmHWM): 6066.38 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3111_df-s04-d0_dw_0_20251103T073346_reco_stage1_20251103T104133_keepup.root
\tHists: np02vd_raw_run040346_3111_df-s04-d0_dw_0_20251103T073346_reco_stage1_20251103T104133_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3111_df-s04-d0_dw_0_20251103T073346_reco_stage1_20251103T104133_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3111_df-s04-d0_dw_0_20251103T073346_reco_stage1_20251103T104133_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": 1762166494.0,
"core.end_time": 1762166494.0,
"core.events": [
287136,
287140,
287144,
287148,
287152,
287156,
287160,
287164,
287168,
287172,
287176,
287180,
287184,
287188,
287192,
287196,
287200,
287204,
287208,
287212,
287216,
287220,
287224
],
"core.event_count": 23,
"core.first_event_number": 287136,
"core.last_event_number": 287224,
"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_3111_df-s04-d0_dw_0_20251103T073346.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3111_df-s04-d0_dw_0_20251103T073346_reco_stage1_20251103T104133_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": 1762166494.0,
"core.end_time": 1762166494.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_3111_df-s04-d0_dw_0_20251103T073346.hdf5"
}
]
} 