Jobsub ID 242425.81@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
anode0sigproc0> EOS at call=47 anode=0
[20:05:24.638] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[20:05:24.638] D [ glue ] frame sink sees EOS
[20:05:24.638] D [ pgraph ] <Pgrapher:> graph execution complete
[20:05:24.638] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.55 sec
[20:05:24.638] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.48 sec
[20:05:24.638] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.21 sec
[20:05:24.638] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.11 sec
[20:05:24.638] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.87 sec
[20:05:24.638] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.72 sec
[20:05:24.638] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.08 sec
[20:05:24.638] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.79 sec
[20:05:24.638] I [ timer ] Timer: WireCell::Aux::Resampler : 0.2 sec
[20:05:24.638] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[20:05:24.638] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[20:05:24.638] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[20:05:24.639] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[20:05:24.639] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:05:24.639] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:05:24.639] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:05:24.639] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:05:24.639] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:05:24.639] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:05:24.639] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:05:24.639] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:05:24.639] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[20:05:24.639] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[20:05:24.639] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[20:05:24.639] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[20:05:24.639] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[20:05:24.639] I [ timer ] Timer: Total node execution : 79.65999945998192 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 45799 traces tagged "gauss"
FrameSaver: q=1.07906e+07 n=920473 tag=gauss
wclsFrameSaver: saving 56778 traces tagged "wiener"
FrameSaver: q=1.16993e+07 n=882902 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1775 XUs and 1562 XVs -> 57 XUVs
C:0 T:1 1255 XUs and 757 XVs -> 47 XUVs
C:0 T:2 386 XUs and 347 XVs -> 10 XUVs
C:0 T:3 590 XUs and 654 XVs -> 11 XUVs
C:0 T:4 2565 XUs and 2822 XVs -> 215 XUVs
C:0 T:5 11737 XUs and 9651 XVs -> 1010 XUVs
C:0 T:6 5505 XUs and 5203 XVs -> 304 XUVs
C:0 T:7 1989 XUs and 1348 XVs -> 102 XUVs
C:0 T:8 2435 XUs and 3237 XVs -> 141 XUVs
C:0 T:9 591 XUs and 672 XVs -> 48 XUVs
C:0 T:10 2451 XUs and 3021 XVs -> 168 XUVs
C:0 T:11 302 XUs and 264 XVs -> 19 XUVs
C:0 T:12 86 XUs and 227 XVs -> 5 XUVs
C:0 T:13 285 XUs and 314 XVs -> 29 XUVs
C:0 T:14 202 XUs and 243 XVs -> 35 XUVs
C:0 T:15 144 XUs and 149 XVs -> 14 XUVs
2215 XUVs total
1415 collection wire objects
2215 potential space points
Neighbour search...
85783 tests to find 21764 neighbours
Iterating with no regularization...
Begin: 2.43606e+09
0 2.29466e+09
1 2.28973e+09
2 2.28963e+09
Now with regularization...
Begin: 2.23922e+09
0 2.23911e+09
03-Nov-2025 20:06:06 CET Closed output file "np02vd_raw_run040346_3697_df-s04-d1_dw_0_20251103T161110_reco_stage1_20251103T190606_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 177.562 303.426 1502.68 243.251 256.229 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.4454e-05 8.50647e-05 0.000205952 7.88795e-05 2.87016e-05 24
produce:tpcrawdecoder:PDVDTPCReader 62.8829 101.826 185.249 92.308 30.7524 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.549252 0.811099 1.81304 0.763754 0.249756 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000281196 0.0003393 0.000738556 0.000321202 8.81203e-05 24
produce:ophit:OpHitFinder 4.9956e-05 7.8215e-05 0.000510752 5.9548e-05 9.03748e-05 24
produce:opflash:OpFlashFinderVerticalDrift 3.8974e-05 5.41314e-05 0.000330078 4.1184e-05 5.76472e-05 24
produce:wclsdatavd:WireCellToolkit 50.7537 96.0705 134.625 87.7247 22.0073 24
produce:gaushit:GausHitFinder 0.817918 1.18476 2.433 1.07578 0.359841 24
produce:nhitsfilter:NumberOfHitsFilter 0.000231831 0.000407702 0.00114244 0.000327307 0.000213033 24
produce:reco3d:SpacePointSolver 7.44535 12.1138 32.2111 10.3914 5.52324 24
produce:hitpdune:DisambigFromSpacePoints 0.105833 0.209745 0.87374 0.146134 0.167868 24
produce:pandora:StandardPandora 15.321 85.9103 1268.9 25.3791 248.252 24
produce:pandoraTrack:LArPandoraTrackCreation 0.520897 1.28585 6.3839 0.880827 1.24714 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0198547 0.0304884 0.0542924 0.029547 0.00688681 24
[art]:TriggerResults:TriggerResultInserter 1.7303e-05 2.61974e-05 5.6878e-05 2.15165e-05 1.01486e-05 24
end_path:out1:RootOutput 4.098e-06 5.91062e-06 2.128e-05 5.1195e-06 3.30824e-06 24
end_path:out1:RootOutput(write) 3.63074 3.92648 4.38954 3.88737 0.217818 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6599.48 MB
Peak resident set size usage (VmHWM): 4573.17 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3697_df-s04-d1_dw_0_20251103T161110_reco_stage1_20251103T190606_keepup.root
\tHists: np02vd_raw_run040346_3697_df-s04-d1_dw_0_20251103T161110_reco_stage1_20251103T190606_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3697_df-s04-d1_dw_0_20251103T161110_reco_stage1_20251103T190606_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3697_df-s04-d1_dw_0_20251103T161110_reco_stage1_20251103T190606_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": 1762196767.0,
"core.end_time": 1762196767.0,
"core.events": [
341425,
341429,
341433,
341437,
341441,
341445,
341449,
341453,
341457,
341461,
341465,
341469,
341473,
341477,
341481,
341485,
341489,
341493,
341497,
341501,
341505,
341509,
341513,
341517
],
"core.event_count": 24,
"core.first_event_number": 341425,
"core.last_event_number": 341517,
"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_3697_df-s04-d1_dw_0_20251103T161110.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3697_df-s04-d1_dw_0_20251103T161110_reco_stage1_20251103T190606_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": 1762196767.0,
"core.end_time": 1762196767.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_3697_df-s04-d1_dw_0_20251103T161110.hdf5"
}
]
} 