Jobsub ID 242471.86@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
gProc:anode0sigproc0> EOS at call=45 anode=0
[21:50:04.318] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[21:50:04.318] D [ glue ] frame sink sees EOS
[21:50:04.319] D [ pgraph ] <Pgrapher:> graph execution complete
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.31 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.92 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.15 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.81 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.29 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.23 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.86 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.53 sec
[21:50:04.319] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[21:50:04.319] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[21:50:04.319] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[21:50:04.319] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[21:50:04.319] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.05 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:50:04.319] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[21:50:04.319] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:50:04.319] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:50:04.319] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:50:04.319] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:50:04.319] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:50:04.319] I [ timer ] Timer: Total node execution : 71.46000037156045 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 61056 traces tagged "gauss"
FrameSaver: q=1.37937e+07 n=1165674 tag=gauss
wclsFrameSaver: saving 79378 traces tagged "wiener"
FrameSaver: q=1.51212e+07 n=1106861 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 4137 XUs and 3908 XVs -> 180 XUVs
C:0 T:1 3497 XUs and 3637 XVs -> 199 XUVs
C:0 T:2 1859 XUs and 1469 XVs -> 111 XUVs
C:0 T:3 1036 XUs and 1156 XVs -> 48 XUVs
C:0 T:4 2327 XUs and 2727 XVs -> 185 XUVs
C:0 T:5 2498 XUs and 1947 XVs -> 144 XUVs
C:0 T:6 1583 XUs and 1442 XVs -> 57 XUVs
C:0 T:7 22255 XUs and 21543 XVs -> 1578 XUVs
C:0 T:8 1114 XUs and 1015 XVs -> 43 XUVs
C:0 T:9 2812 XUs and 3813 XVs -> 255 XUVs
C:0 T:10 333 XUs and 315 XVs -> 20 XUVs
C:0 T:11 920 XUs and 745 XVs -> 61 XUVs
C:0 T:12 330 XUs and 550 XVs -> 20 XUVs
C:0 T:13 649 XUs and 859 XVs -> 52 XUVs
C:0 T:14 1493 XUs and 1236 XVs -> 146 XUVs
C:0 T:15 469 XUs and 544 XVs -> 33 XUVs
3132 XUVs total
1964 collection wire objects
3132 potential space points
Neighbour search...
100534 tests to find 31774 neighbours
Iterating with no regularization...
Begin: 3.57932e+09
0 3.35296e+09
1 3.33879e+09
2 3.33851e+09
Now with regularization...
Begin: 3.29195e+09
0 3.29178e+09
03-Nov-2025 21:51:24 GMT Closed output file "np02vd_raw_run040346_3753_df-s04-d2_dw_0_20251103T170025_reco_stage1_20251103T215124_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 190.092 611.307 8656.52 234.425 1716.21 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 0.000103685 0.000163695 0.000246673 0.000162616 3.88089e-05 23
produce:tpcrawdecoder:PDVDTPCReader 58.4803 86.0831 127.865 83.7775 15.3225 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.763911 0.95292 2.28575 0.881082 0.311369 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000434566 0.000584346 0.00106953 0.000556196 0.000143359 23
produce:ophit:OpHitFinder 0.000103595 0.000221084 0.000910521 0.000188304 0.000158973 23
produce:opflash:OpFlashFinderVerticalDrift 6.874e-05 0.000251978 0.00251056 0.000142437 0.00049221 23
produce:wclsdatavd:WireCellToolkit 68.4552 89.6976 152.42 84.5608 21.444 23
produce:gaushit:GausHitFinder 0.806176 1.77109 3.19856 1.75493 0.498002 23
produce:nhitsfilter:NumberOfHitsFilter 0.00026041 0.000445696 0.00109267 0.000420851 0.00015813 23
produce:reco3d:SpacePointSolver 7.22562 16.5011 65.7326 14.259 11.3856 23
produce:hitpdune:DisambigFromSpacePoints 0.141425 0.314309 1.19357 0.239586 0.216435 23
produce:pandora:StandardPandora 12.3278 407.366 8350.7 31.71 1693.88 23
produce:pandoraTrack:LArPandoraTrackCreation 0.456875 2.10317 7.24908 1.66527 1.49874 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0212526 0.0547065 0.100048 0.0517755 0.018072 23
[art]:TriggerResults:TriggerResultInserter 5.1246e-05 8.85085e-05 0.000133461 8.8646e-05 2.5157e-05 23
end_path:out1:RootOutput 6.422e-06 2.02859e-05 4.8882e-05 1.7342e-05 9.2853e-06 23
end_path:out1:RootOutput(write) 5.07772 6.3407 7.61337 6.32389 0.686015 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5907.44 MB
Peak resident set size usage (VmHWM): 3825.3 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3753_df-s04-d2_dw_0_20251103T170025_reco_stage1_20251103T215124_keepup.root
\tHists: np02vd_raw_run040346_3753_df-s04-d2_dw_0_20251103T170025_reco_stage1_20251103T215124_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3753_df-s04-d2_dw_0_20251103T170025_reco_stage1_20251103T215124_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3753_df-s04-d2_dw_0_20251103T170025_reco_stage1_20251103T215124_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": 1762206685.0,
"core.end_time": 1762206685.0,
"core.events": [
346534,
346538,
346542,
346546,
346550,
346554,
346558,
346562,
346566,
346570,
346574,
346578,
346582,
346586,
346590,
346594,
346598,
346602,
346606,
346610,
346614,
346618,
346622
],
"core.event_count": 23,
"core.first_event_number": 346534,
"core.last_event_number": 346622,
"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_3753_df-s04-d2_dw_0_20251103T170025.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3753_df-s04-d2_dw_0_20251103T170025_reco_stage1_20251103T215124_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": 1762206685.0,
"core.end_time": 1762206685.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_3753_df-s04-d2_dw_0_20251103T170025.hdf5"
}
]
} 