Jobsub ID 242250.145@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
8] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[13:18:11.878] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[13:18:11.878] D [ glue ] frame sink sees EOS
[13:18:11.879] D [ pgraph ] <Pgrapher:> graph execution complete
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.01 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.01 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.5 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.19 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.95 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.08 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.1 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.82 sec
[13:18:11.879] I [ timer ] Timer: WireCell::Aux::Resampler : 0.53 sec
[13:18:11.879] I [ timer ] Timer: WireCell::Aux::Resampler : 0.52 sec
[13:18:11.879] I [ timer ] Timer: WireCell::Aux::Resampler : 0.37 sec
[13:18:11.879] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[13:18:11.879] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:18:11.879] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:18:11.879] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:18:11.879] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[13:18:11.879] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:18:11.879] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:18:11.879] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:18:11.879] I [ timer ] Timer: Total node execution : 84.44000028260052 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 59645 traces tagged "gauss"
FrameSaver: q=1.8827e+07 n=1316175 tag=gauss
wclsFrameSaver: saving 74954 traces tagged "wiener"
FrameSaver: q=2.0269e+07 n=1263613 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 17724 XUs and 46087 XVs -> 1564 XUVs
C:0 T:1 765 XUs and 634 XVs -> 37 XUVs
C:0 T:2 2491 XUs and 3907 XVs -> 132 XUVs
C:0 T:3 1646 XUs and 1277 XVs -> 39 XUVs
C:0 T:4 1907 XUs and 2402 XVs -> 137 XUVs
C:0 T:5 1379 XUs and 1629 XVs -> 70 XUVs
C:0 T:6 1388 XUs and 1238 XVs -> 48 XUVs
C:0 T:7 3150 XUs and 4570 XVs -> 322 XUVs
C:0 T:8 89 XUs and 220 XVs -> 8 XUVs
C:0 T:9 952 XUs and 1332 XVs -> 60 XUVs
C:0 T:10 515 XUs and 756 XVs -> 33 XUVs
C:0 T:11 199 XUs and 187 XVs -> 8 XUVs
C:0 T:12 447 XUs and 683 XVs -> 43 XUVs
C:0 T:13 1769 XUs and 2529 XVs -> 113 XUVs
C:0 T:14 3639 XUs and 3218 XVs -> 191 XUVs
C:0 T:15 1303 XUs and 1583 XVs -> 63 XUVs
2868 XUVs total
1943 collection wire objects
2868 potential space points
Neighbour search...
57052 tests to find 17584 neighbours
Iterating with no regularization...
Begin: 1.68476e+11
0 1.66369e+11
1 1.66326e+11
Now with regularization...
Begin: 1.66156e+11
0 1.66154e+11
03-Nov-2025 13:19:18 CET Closed output file "np02vd_raw_run040346_2995_df-s04-d0_dw_0_20251103T055826_reco_stage1_20251103T121918_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 85.9024 576.895 8341.44 139.26 1677.65 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.0002e-05 0.000109549 0.000181624 0.000105202 3.11562e-05 23
produce:tpcrawdecoder:PDVDTPCReader 4.50518 5.28439 8.65736 4.99325 0.86837 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.0124572 0.0202471 0.0684473 0.0146773 0.0129764 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000278586 0.000403508 0.000735435 0.000379578 9.03942e-05 23
produce:ophit:OpHitFinder 7.0861e-05 0.000141747 0.000613453 0.000111832 0.000107717 23
produce:opflash:OpFlashFinderVerticalDrift 4.9691e-05 8.59166e-05 0.000337377 7.3452e-05 5.55905e-05 23
produce:wclsdatavd:WireCellToolkit 48.9799 80.0768 173.189 70.4386 31.3027 23
produce:gaushit:GausHitFinder 0.690371 1.50535 2.94637 1.50153 0.572508 23
produce:nhitsfilter:NumberOfHitsFilter 0.000171853 0.000309074 0.00050711 0.000293926 8.80636e-05 23
produce:reco3d:SpacePointSolver 6.09474 16.5799 55.0357 12.8301 11.8701 23
produce:hitpdune:DisambigFromSpacePoints 0.126983 0.335367 1.21649 0.205707 0.305817 23
produce:pandora:StandardPandora 15.8732 463.221 8119.4 31.5857 1649.63 23
produce:pandoraTrack:LArPandoraTrackCreation 0.40473 1.6909 7.90311 1.17367 1.62146 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.016062 0.0396131 0.074068 0.0407208 0.0151075 23
[art]:TriggerResults:TriggerResultInserter 1.7441e-05 3.11742e-05 8.7012e-05 2.8151e-05 1.30497e-05 23
end_path:out1:RootOutput 4.23e-06 9.58978e-06 3.0891e-05 8.77e-06 5.46525e-06 23
end_path:out1:RootOutput(write) 5.89647 8.08509 11.1708 8.04347 1.43225 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 11639.9 MB
Peak resident set size usage (VmHWM): 9564.09 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_2995_df-s04-d0_dw_0_20251103T055826_reco_stage1_20251103T121918_keepup.root
\tHists: np02vd_raw_run040346_2995_df-s04-d0_dw_0_20251103T055826_reco_stage1_20251103T121918_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_2995_df-s04-d0_dw_0_20251103T055826_reco_stage1_20251103T121918_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_2995_df-s04-d0_dw_0_20251103T055826_reco_stage1_20251103T121918_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": 1762172359.0,
"core.end_time": 1762172359.0,
"core.events": [
276456,
276460,
276464,
276468,
276472,
276476,
276480,
276484,
276488,
276492,
276496,
276500,
276504,
276508,
276512,
276516,
276520,
276524,
276528,
276532,
276536,
276540,
276544
],
"core.event_count": 23,
"core.first_event_number": 276456,
"core.last_event_number": 276544,
"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_2995_df-s04-d0_dw_0_20251103T055826.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_2995_df-s04-d0_dw_0_20251103T055826_reco_stage1_20251103T121918_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": 1762172359.0,
"core.end_time": 1762172359.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_2995_df-s04-d0_dw_0_20251103T055826.hdf5"
}
]
} 