Jobsub ID 237548.0@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[05:10:52.789] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[05:10:52.789] D [ glue ] frame sink sees EOS
[05:10:52.790] D [ pgraph ] <Pgrapher:> graph execution complete
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.1 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.63 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.16 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.05 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.98 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.22 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.17 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.3 sec
[05:10:52.790] I [ timer ] Timer: WireCell::Aux::Resampler : 0.6 sec
[05:10:52.790] I [ timer ] Timer: WireCell::Aux::Resampler : 0.6 sec
[05:10:52.790] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[05:10:52.790] I [ timer ] Timer: WireCell::Aux::Resampler : 0.34 sec
[05:10:52.790] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.03 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:10:52.790] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:10:52.790] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[05:10:52.790] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:10:52.790] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[05:10:52.790] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[05:10:52.790] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[05:10:52.790] I [ timer ] Timer: Total node execution : 103.56000065430999 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 47809 traces tagged "gauss"
FrameSaver: q=1.22814e+07 n=997690 tag=gauss
wclsFrameSaver: saving 59366 traces tagged "wiener"
FrameSaver: q=1.32061e+07 n=949273 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 188 XUs and 284 XVs -> 12 XUVs
C:0 T:1 363 XUs and 259 XVs -> 15 XUVs
C:0 T:2 537 XUs and 760 XVs -> 62 XUVs
C:0 T:3 215 XUs and 240 XVs -> 3 XUVs
C:0 T:4 150 XUs and 195 XVs -> 7 XUVs
C:0 T:5 1675 XUs and 1633 XVs -> 64 XUVs
C:0 T:6 1044 XUs and 563 XVs -> 20 XUVs
C:0 T:7 26569 XUs and 25526 XVs -> 2224 XUVs
C:0 T:8 1500 XUs and 1881 XVs -> 112 XUVs
C:0 T:9 592 XUs and 883 XVs -> 42 XUVs
C:0 T:10 1541 XUs and 2238 XVs -> 75 XUVs
C:0 T:11 89 XUs and 119 XVs -> 8 XUVs
C:0 T:12 819 XUs and 1037 XVs -> 59 XUVs
C:0 T:13 1392 XUs and 1468 XVs -> 78 XUVs
C:0 T:14 2336 XUs and 2187 XVs -> 148 XUVs
C:0 T:15 1246 XUs and 1146 XVs -> 64 XUVs
2993 XUVs total
1706 collection wire objects
2993 potential space points
Neighbour search...
144829 tests to find 32198 neighbours
Iterating with no regularization...
Begin: 1.41572e+10
0 1.35432e+10
1 1.34883e+10
2 1.34873e+10
Now with regularization...
Begin: 1.32084e+10
0 1.32076e+10
05-Nov-2025 05:11:43 UTC Closed output file "np02vd_raw_run040346_2559_df-s04-d2_dw_0_20251102T235455_reco_stage1_20251105T051143_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 89.1883 712.961 4690.73 164.14 1409.88 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.1923e-05 0.000190234 0.00162811 0.000114884 0.000308801 23
produce:tpcrawdecoder:PDVDTPCReader 13.687 14.6574 18.7999 14.4049 1.05708 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.683531 0.699491 0.733407 0.691516 0.0157605 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000247063 0.000443139 0.000821739 0.000400404 0.00014317 23
produce:ophit:OpHitFinder 7.262e-05 0.000162229 0.000691073 0.000134773 0.000122902 23
produce:opflash:OpFlashFinderVerticalDrift 5.3972e-05 0.00010398 0.000404781 8.592e-05 7.46937e-05 23
produce:wclsdatavd:WireCellToolkit 46.4137 115.819 470.378 77.0203 110.627 23
produce:gaushit:GausHitFinder 0.697593 2.74406 17.4181 1.1582 3.9963 23
produce:nhitsfilter:NumberOfHitsFilter 0.000175054 0.000395195 0.000932206 0.000337449 0.000213666 23
produce:reco3d:SpacePointSolver 6.11162 21.7855 102.041 11.4146 25.1999 23
produce:hitpdune:DisambigFromSpacePoints 0.091803 0.417199 1.79529 0.221354 0.482273 23
produce:pandora:StandardPandora 12.8398 549.195 4387.87 54.3547 1286.7 23
produce:pandoraTrack:LArPandoraTrackCreation 0.359341 2.86184 28.2172 1.10498 5.85975 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0180007 0.0402813 0.168509 0.0315711 0.0312502 23
[art]:TriggerResults:TriggerResultInserter 2.1803e-05 4.63393e-05 0.000122425 4.4747e-05 2.05286e-05 23
end_path:out1:RootOutput 6.239e-06 1.30792e-05 2.1412e-05 1.318e-05 3.60065e-06 23
end_path:out1:RootOutput(write) 3.4945 4.64585 7.16706 4.35232 1.07215 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 11095.8 MB
Peak resident set size usage (VmHWM): 8724.55 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_2559_df-s04-d2_dw_0_20251102T235455_reco_stage1_20251105T051143_keepup.root
\tHists: np02vd_raw_run040346_2559_df-s04-d2_dw_0_20251102T235455_reco_stage1_20251105T051144_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_2559_df-s04-d2_dw_0_20251102T235455_reco_stage1_20251105T051143_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_2559_df-s04-d2_dw_0_20251102T235455_reco_stage1_20251105T051143_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": 1762319505.0,
"core.end_time": 1762319505.0,
"core.events": [
236246,
236250,
236254,
236258,
236262,
236266,
236270,
236274,
236278,
236282,
236286,
236290,
236294,
236298,
236302,
236306,
236310,
236314,
236318,
236322,
236326,
236330,
236334
],
"core.event_count": 23,
"core.first_event_number": 236246,
"core.last_event_number": 236334,
"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_2559_df-s04-d2_dw_0_20251102T235455.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_2559_df-s04-d2_dw_0_20251102T235455_reco_stage1_20251105T051144_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": 1762319505.0,
"core.end_time": 1762319505.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_2559_df-s04-d2_dw_0_20251102T235455.hdf5"
}
]
} 