Jobsub ID 242425.3@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
] <OmnibusSigProc:anode0sigproc0> EOS at call=47 anode=0
[18:48:39.142] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[18:48:39.142] D [ glue ] frame sink sees EOS
[18:48:39.142] D [ pgraph ] <Pgrapher:> graph execution complete
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.09 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.06 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.48 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.48 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.42 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.28 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.25 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.22 sec
[18:48:39.142] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[18:48:39.142] I [ timer ] Timer: WireCell::Aux::Resampler : 0.2 sec
[18:48:39.142] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[18:48:39.142] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[18:48:39.142] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:48:39.142] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:48:39.142] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:48:39.142] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[18:48:39.142] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[18:48:39.142] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[18:48:39.142] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[18:48:39.142] I [ timer ] Timer: Total node execution : 63.20000069402158 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 40110 traces tagged "gauss"
FrameSaver: q=1.06755e+07 n=864635 tag=gauss
wclsFrameSaver: saving 50974 traces tagged "wiener"
FrameSaver: q=1.15117e+07 n=823349 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 237 XUs and 284 XVs -> 5 XUVs
C:0 T:1 131 XUs and 227 XVs -> 2 XUVs
C:0 T:2 616 XUs and 780 XVs -> 15 XUVs
C:0 T:3 91 XUs and 83 XVs -> 3 XUVs
C:0 T:4 9232 XUs and 7574 XVs -> 646 XUVs
C:0 T:5 6397 XUs and 5480 XVs -> 492 XUVs
C:0 T:6 5189 XUs and 3489 XVs -> 310 XUVs
C:0 T:7 393 XUs and 519 XVs -> 15 XUVs
C:0 T:8 306 XUs and 419 XVs -> 14 XUVs
C:0 T:9 898 XUs and 707 XVs -> 35 XUVs
C:0 T:10 983 XUs and 1324 XVs -> 80 XUVs
C:0 T:11 2934 XUs and 3297 XVs -> 203 XUVs
C:0 T:12 1739 XUs and 1839 XVs -> 141 XUVs
C:0 T:13 222 XUs and 234 XVs -> 17 XUVs
C:0 T:14 1222 XUs and 1083 XVs -> 70 XUVs
C:0 T:15 88 XUs and 104 XVs -> 10 XUVs
2058 XUVs total
1357 collection wire objects
2058 potential space points
Neighbour search...
50796 tests to find 15016 neighbours
Iterating with no regularization...
Begin: 4.3813e+09
0 4.09357e+09
1 4.08138e+09
2 4.07997e+09
Now with regularization...
Begin: 4.01613e+09
0 4.01561e+09
03-Nov-2025 18:49:18 CET Closed output file "np02vd_raw_run040346_3681_df-s04-d3_dw_0_20251103T155302_reco_stage1_20251103T174918_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 91.1405 127.125 182.631 116.131 25.3009 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.3431e-05 0.000110432 0.000232577 0.00011388 3.62829e-05 24
produce:tpcrawdecoder:PDVDTPCReader 5.3109 7.18727 9.45277 7.32603 1.02461 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.0786423 0.0888166 0.13287 0.0847776 0.0131129 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000305605 0.000418677 0.00110366 0.00039084 0.000147027 24
produce:ophit:OpHitFinder 7.5402e-05 0.000147174 0.000915904 0.000107332 0.000162661 24
produce:opflash:OpFlashFinderVerticalDrift 4.4805e-05 7.8618e-05 0.000493239 6.33445e-05 8.70671e-05 24
produce:wclsdatavd:WireCellToolkit 53.8513 73.9659 104.631 65.6221 16.3813 24
produce:gaushit:GausHitFinder 0.785435 1.14822 1.83037 1.1172 0.242541 24
produce:nhitsfilter:NumberOfHitsFilter 0.000206368 0.000344401 0.000711309 0.000324175 9.73929e-05 24
produce:reco3d:SpacePointSolver 7.69827 10.9939 15.803 11.0078 2.02263 24
produce:hitpdune:DisambigFromSpacePoints 0.0833227 0.172932 0.30284 0.172819 0.0624273 24
produce:pandora:StandardPandora 12.8209 27.6156 51.8912 24.6883 10.4663 24
produce:pandoraTrack:LArPandoraTrackCreation 0.411646 0.939812 1.58319 0.884702 0.329469 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0225904 0.0353988 0.0555873 0.0350523 0.0082852 24
[art]:TriggerResults:TriggerResultInserter 2.0339e-05 5.10436e-05 0.000243489 3.22755e-05 5.21223e-05 24
end_path:out1:RootOutput 4.098e-06 1.00485e-05 3.8473e-05 8.496e-06 7.14607e-06 24
end_path:out1:RootOutput(write) 4.2999 4.91677 6.14344 4.74083 0.493777 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5594.72 MB
Peak resident set size usage (VmHWM): 3538 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3681_df-s04-d3_dw_0_20251103T155302_reco_stage1_20251103T174918_keepup.root
\tHists: np02vd_raw_run040346_3681_df-s04-d3_dw_0_20251103T155302_reco_stage1_20251103T174918_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3681_df-s04-d3_dw_0_20251103T155302_reco_stage1_20251103T174918_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3681_df-s04-d3_dw_0_20251103T155302_reco_stage1_20251103T174918_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": 1762192159.0,
"core.end_time": 1762192159.0,
"core.events": [
339611,
339615,
339619,
339623,
339627,
339631,
339635,
339639,
339643,
339647,
339651,
339655,
339659,
339663,
339667,
339671,
339675,
339679,
339683,
339687,
339691,
339695,
339699,
339703
],
"core.event_count": 24,
"core.first_event_number": 339611,
"core.last_event_number": 339703,
"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_3681_df-s04-d3_dw_0_20251103T155302.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3681_df-s04-d3_dw_0_20251103T155302_reco_stage1_20251103T174918_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": 1762192159.0,
"core.end_time": 1762192159.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_3681_df-s04-d3_dw_0_20251103T155302.hdf5"
}
]
} 