Jobsub ID 242425.175@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[18:32:56.794] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[18:32:56.794] D [ glue ] frame sink sees EOS
[18:32:56.794] D [ pgraph ] <Pgrapher:> graph execution complete
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.35 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.48 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.31 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.24 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.15 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.12 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.75 sec
[18:32:56.794] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[18:32:56.794] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[18:32:56.794] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[18:32:56.794] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[18:32:56.794] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:32:56.794] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:32:56.794] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:32:56.794] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:32:56.794] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[18:32:56.794] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[18:32:56.794] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[18:32:56.794] I [ timer ] Timer: Total node execution : 68.38999961316586 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 40517 traces tagged "gauss"
FrameSaver: q=1.10824e+07 n=871671 tag=gauss
wclsFrameSaver: saving 50835 traces tagged "wiener"
FrameSaver: q=1.21865e+07 n=839427 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 420 XUs and 587 XVs -> 29 XUVs
C:0 T:1 1049 XUs and 798 XVs -> 20 XUVs
C:0 T:2 12308 XUs and 13566 XVs -> 1424 XUVs
C:0 T:3 5848 XUs and 6463 XVs -> 534 XUVs
C:0 T:4 4037 XUs and 3528 XVs -> 455 XUVs
C:0 T:5 613 XUs and 474 XVs -> 87 XUVs
C:0 T:6 455 XUs and 516 XVs -> 40 XUVs
C:0 T:7 629 XUs and 610 XVs -> 40 XUVs
C:0 T:8 809 XUs and 1204 XVs -> 72 XUVs
C:0 T:9 143 XUs and 106 XVs -> 6 XUVs
C:0 T:10 1081 XUs and 1068 XVs -> 63 XUVs
C:0 T:11 944 XUs and 1332 XVs -> 92 XUVs
C:0 T:12 65 XUs and 131 XVs -> 7 XUVs
C:0 T:13 2344 XUs and 2089 XVs -> 130 XUVs
C:0 T:14 392 XUs and 284 XVs -> 21 XUVs
C:0 T:15 788 XUs and 712 XVs -> 49 XUVs
3069 XUVs total
1335 collection wire objects
3069 potential space points
Neighbour search...
143321 tests to find 40248 neighbours
Iterating with no regularization...
Begin: 6.65459e+09
0 5.65674e+09
1 5.54618e+09
2 5.54076e+09
Now with regularization...
Begin: 5.43251e+09
0 5.43093e+09
03-Nov-2025 18:33:34 GMT Closed output file "np02vd_raw_run040346_3728_df-s04-d0_dw_0_20251103T163729_reco_stage1_20251103T183334_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 120.544 209.624 413.909 193.035 71.2619 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.5469e-05 0.000114958 0.000282079 0.000101262 4.0201e-05 23
produce:tpcrawdecoder:PDVDTPCReader 14.0226 15.2496 17.9159 15.0319 0.976229 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.493098 0.511968 0.573079 0.507719 0.022144 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000396219 0.000691065 0.00188609 0.000594716 0.000284726 23
produce:ophit:OpHitFinder 7.2031e-05 0.000218326 0.00166365 0.000151 0.000310539 23
produce:opflash:OpFlashFinderVerticalDrift 6.3176e-05 0.000160688 0.0011144 0.000113918 0.000206735 23
produce:wclsdatavd:WireCellToolkit 69.1484 109.668 184.289 100.263 27.3812 23
produce:gaushit:GausHitFinder 1.00999 1.91171 3.10792 1.94771 0.485719 23
produce:nhitsfilter:NumberOfHitsFilter 0.000452561 0.000633544 0.00116583 0.000594024 0.000157024 23
produce:reco3d:SpacePointSolver 8.56688 13.9275 22.4675 14.1069 3.05751 23
produce:hitpdune:DisambigFromSpacePoints 0.156531 0.331089 0.536223 0.32664 0.0893181 23
produce:pandora:StandardPandora 19.8486 57.8641 235.518 46.5619 50.0404 23
produce:pandoraTrack:LArPandoraTrackCreation 1.65802 3.78028 6.39038 3.47076 1.26487 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0356244 0.0571176 0.0874095 0.0559848 0.0116533 23
[art]:TriggerResults:TriggerResultInserter 5.7463e-05 7.78335e-05 0.000171797 7.5976e-05 2.08247e-05 23
end_path:out1:RootOutput 1.2e-05 1.96237e-05 5.8291e-05 1.7808e-05 8.91955e-06 23
end_path:out1:RootOutput(write) 3.61204 6.24031 8.19395 6.29641 0.888423 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5363.98 MB
Peak resident set size usage (VmHWM): 3332.1 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3728_df-s04-d0_dw_0_20251103T163729_reco_stage1_20251103T183334_keepup.root
\tHists: np02vd_raw_run040346_3728_df-s04-d0_dw_0_20251103T163729_reco_stage1_20251103T183335_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3728_df-s04-d0_dw_0_20251103T163729_reco_stage1_20251103T183334_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3728_df-s04-d0_dw_0_20251103T163729_reco_stage1_20251103T183334_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": 1762194815.0,
"core.end_time": 1762194815.0,
"core.events": [
344136,
344140,
344144,
344148,
344152,
344156,
344160,
344164,
344168,
344172,
344176,
344180,
344184,
344188,
344192,
344196,
344200,
344204,
344208,
344212,
344216,
344220,
344224
],
"core.event_count": 23,
"core.first_event_number": 344136,
"core.last_event_number": 344224,
"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_3728_df-s04-d0_dw_0_20251103T163729.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3728_df-s04-d0_dw_0_20251103T163729_reco_stage1_20251103T183335_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": 1762194815.0,
"core.end_time": 1762194815.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_3728_df-s04-d0_dw_0_20251103T163729.hdf5"
}
]
} 