Jobsub ID 242278.139@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[12:54:37.762] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[12:54:37.762] D [ glue ] frame sink sees EOS
[12:54:37.762] D [ pgraph ] <Pgrapher:> graph execution complete
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.6 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.46 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.07 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.69 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.51 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.5 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.83 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.35 sec
[12:54:37.762] I [ timer ] Timer: WireCell::Aux::Resampler : 1.51 sec
[12:54:37.762] I [ timer ] Timer: WireCell::Aux::Resampler : 1.48 sec
[12:54:37.762] I [ timer ] Timer: WireCell::Aux::Resampler : 1.47 sec
[12:54:37.762] I [ timer ] Timer: WireCell::Aux::Resampler : 1.02 sec
[12:54:37.762] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:54:37.762] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:54:37.762] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:54:37.762] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:54:37.762] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:54:37.762] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:54:37.762] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:54:37.762] I [ timer ] Timer: Total node execution : 122.53000024706125 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 53122 traces tagged "gauss"
FrameSaver: q=1.709e+07 n=1120861 tag=gauss
wclsFrameSaver: saving 66306 traces tagged "wiener"
FrameSaver: q=1.82135e+07 n=1066427 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1071 XUs and 552 XVs -> 37 XUVs
C:0 T:1 482 XUs and 368 XVs -> 46 XUVs
C:0 T:2 2071 XUs and 1224 XVs -> 83 XUVs
C:0 T:3 641 XUs and 825 XVs -> 26 XUVs
C:0 T:4 899 XUs and 1308 XVs -> 57 XUVs
C:0 T:5 19803 XUs and 23543 XVs -> 1803 XUVs
C:0 T:6 68 XUs and 84 XVs -> 0 XUVs
C:0 T:7 3871 XUs and 8441 XVs -> 288 XUVs
C:0 T:8 1184 XUs and 1560 XVs -> 53 XUVs
C:0 T:9 3114 XUs and 3268 XVs -> 107 XUVs
C:0 T:10 1277 XUs and 1726 XVs -> 64 XUVs
C:0 T:11 1514 XUs and 1789 XVs -> 74 XUVs
C:0 T:12 1190 XUs and 1465 XVs -> 40 XUVs
C:0 T:13 1406 XUs and 1596 XVs -> 74 XUVs
C:0 T:14 1033 XUs and 1471 XVs -> 61 XUVs
C:0 T:15 266 XUs and 256 XVs -> 19 XUVs
2832 XUVs total
1734 collection wire objects
2832 potential space points
Neighbour search...
109840 tests to find 23868 neighbours
Iterating with no regularization...
Begin: 2.10942e+10
0 2.04039e+10
1 2.03597e+10
2 2.03593e+10
Now with regularization...
Begin: 2.00612e+10
0 2.00611e+10
03-Nov-2025 12:56:06 GMT Closed output file "np02vd_raw_run040346_3282_df-s04-d0_dw_0_20251103T100114_reco_stage1_20251103T125606_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 195.893 340.478 1762.5 259.536 308.707 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.2469e-05 0.000102917 0.000269511 9.293e-05 3.76828e-05 23
produce:tpcrawdecoder:PDVDTPCReader 64.4856 98.6443 161.501 99.6972 22.6371 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.593323 0.806882 1.21603 0.798666 0.156758 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000446984 0.00054139 0.00104224 0.000520466 0.000116232 23
produce:ophit:OpHitFinder 8.0216e-05 0.000129755 0.000779888 9.9122e-05 0.000139242 23
produce:opflash:OpFlashFinderVerticalDrift 6.7571e-05 0.000104829 0.000475489 8.3462e-05 8.07865e-05 23
produce:wclsdatavd:WireCellToolkit 71.3188 102.832 160.619 92.618 27.4745 23
produce:gaushit:GausHitFinder 1.1849 1.91966 3.12784 1.82995 0.528673 23
produce:nhitsfilter:NumberOfHitsFilter 0.0002637 0.000398593 0.000741624 0.000391227 0.000112979 23
produce:reco3d:SpacePointSolver 9.0969 17.9066 46.0495 15.0405 7.99084 23
produce:hitpdune:DisambigFromSpacePoints 0.128212 0.342993 1.46828 0.278788 0.261813 23
produce:pandora:StandardPandora 17.0519 109.09 1458.97 40.5407 289.545 23
produce:pandoraTrack:LArPandoraTrackCreation 0.532225 1.60765 3.47132 1.44966 0.707317 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0319075 0.056046 0.0863861 0.0561182 0.0135748 23
[art]:TriggerResults:TriggerResultInserter 2.8455e-05 4.19065e-05 0.000122276 3.6832e-05 1.88487e-05 23
end_path:out1:RootOutput 5.791e-06 7.613e-06 2.8285e-05 6.362e-06 4.53841e-06 23
end_path:out1:RootOutput(write) 6.37041 7.19593 8.115 7.16748 0.422217 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6459.29 MB
Peak resident set size usage (VmHWM): 4375.3 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3282_df-s04-d0_dw_0_20251103T100114_reco_stage1_20251103T125606_keepup.root
\tHists: np02vd_raw_run040346_3282_df-s04-d0_dw_0_20251103T100114_reco_stage1_20251103T125607_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3282_df-s04-d0_dw_0_20251103T100114_reco_stage1_20251103T125606_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3282_df-s04-d0_dw_0_20251103T100114_reco_stage1_20251103T125606_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": 1762174568.0,
"core.end_time": 1762174568.0,
"core.events": [
302932,
302936,
302940,
302944,
302948,
302952,
302956,
302960,
302964,
302968,
302972,
302976,
302980,
302984,
302988,
302992,
302996,
303000,
303004,
303008,
303012,
303016,
303020
],
"core.event_count": 23,
"core.first_event_number": 302932,
"core.last_event_number": 303020,
"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_3282_df-s04-d0_dw_0_20251103T100114.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3282_df-s04-d0_dw_0_20251103T100114_reco_stage1_20251103T125607_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": 1762174568.0,
"core.end_time": 1762174568.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_3282_df-s04-d0_dw_0_20251103T100114.hdf5"
}
]
} 