Jobsub ID 241758.97@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
469] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[22:42:29.469] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[22:42:29.469] D [ glue ] frame sink sees EOS
[22:42:29.469] D [ pgraph ] <Pgrapher:> graph execution complete
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.4 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.4 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.37 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.97 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.96 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.85 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.82 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.22 sec
[22:42:29.469] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[22:42:29.469] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[22:42:29.469] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[22:42:29.469] I [ timer ] Timer: WireCell::Aux::Resampler : 0.2 sec
[22:42:29.469] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[22:42:29.469] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:42:29.469] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:42:29.469] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:42:29.469] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[22:42:29.469] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[22:42:29.469] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[22:42:29.469] I [ timer ] Timer: Total node execution : 86.84999975375831 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 39581 traces tagged "gauss"
FrameSaver: q=1.13199e+07 n=846544 tag=gauss
wclsFrameSaver: saving 49838 traces tagged "wiener"
FrameSaver: q=1.25531e+07 n=813611 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 206 XUs and 187 XVs -> 6 XUVs
C:0 T:1 14404 XUs and 8280 XVs -> 1866 XUVs
C:0 T:2 40719 XUs and 33539 XVs -> 3554 XUVs
C:0 T:3 2265 XUs and 1624 XVs -> 413 XUVs
C:0 T:4 5191 XUs and 3469 XVs -> 1219 XUVs
C:0 T:5 540 XUs and 546 XVs -> 6 XUVs
C:0 T:6 113 XUs and 109 XVs -> 2 XUVs
C:0 T:7 201 XUs and 164 XVs -> 7 XUVs
C:0 T:8 800 XUs and 1211 XVs -> 78 XUVs
C:0 T:9 820 XUs and 916 XVs -> 66 XUVs
C:0 T:10 473 XUs and 529 XVs -> 30 XUVs
C:0 T:11 514 XUs and 727 XVs -> 31 XUVs
C:0 T:12 1406 XUs and 2340 XVs -> 118 XUVs
C:0 T:13 1877 XUs and 1860 XVs -> 65 XUVs
C:0 T:14 460 XUs and 561 XVs -> 14 XUVs
C:0 T:15 1107 XUs and 1264 XVs -> 139 XUVs
7614 XUVs total
1547 collection wire objects
7614 potential space points
Neighbour search...
643314 tests to find 263076 neighbours
Iterating with no regularization...
Begin: 1.53614e+10
0 1.28471e+10
1 1.25604e+10
2 1.25321e+10
3 1.25249e+10
Now with regularization...
Begin: 1.24427e+10
0 1.2439e+10
01-Nov-2025 22:43:14 CET Closed output file "np02vd_raw_run040346_0532_df-s04-d1_dw_0_20251101T191822_reco_stage1_20251101T214314_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 103.291 173.251 686.534 140.446 115.867 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.5093e-05 7.05084e-05 0.00019734 6.3209e-05 2.80722e-05 23
produce:tpcrawdecoder:PDVDTPCReader 10.973 11.9489 14.6973 11.41 0.889406 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.516909 0.52519 0.55274 0.521177 0.00865035 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000372379 0.000403283 0.000755009 0.000386977 7.54636e-05 23
produce:ophit:OpHitFinder 6.2247e-05 9.40136e-05 0.000579179 6.922e-05 0.00010377 23
produce:opflash:OpFlashFinderVerticalDrift 5.0275e-05 6.64191e-05 0.00032986 5.3921e-05 5.62538e-05 23
produce:wclsdatavd:WireCellToolkit 52.7364 83.7294 130.199 80.2359 21.1395 23
produce:gaushit:GausHitFinder 0.790716 1.35404 2.92131 1.30805 0.418201 23
produce:nhitsfilter:NumberOfHitsFilter 0.00023973 0.000328103 0.000581592 0.000322757 8.49655e-05 23
produce:reco3d:SpacePointSolver 7.58682 14.2003 41.5602 12.8529 6.77244 23
produce:hitpdune:DisambigFromSpacePoints 0.097344 0.243443 1.31889 0.184352 0.237206 23
produce:pandora:StandardPandora 12.3817 54.7058 482.486 31.9326 93.1413 23
produce:pandoraTrack:LArPandoraTrackCreation 0.425773 1.42491 8.47495 1.05994 1.56524 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0232899 0.0357813 0.0639784 0.0353107 0.00880134 23
[art]:TriggerResults:TriggerResultInserter 1.9026e-05 2.77719e-05 6.8309e-05 2.5238e-05 9.75757e-06 23
end_path:out1:RootOutput 3.938e-06 7.6353e-06 3.0788e-05 7.524e-06 5.20949e-06 23
end_path:out1:RootOutput(write) 4.49751 5.0348 6.14135 4.94352 0.407311 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7793.85 MB
Peak resident set size usage (VmHWM): 5768.02 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_0532_df-s04-d1_dw_0_20251101T191822_reco_stage1_20251101T214314_keepup.root
\tHists: np02vd_raw_run040346_0532_df-s04-d1_dw_0_20251101T191822_reco_stage1_20251101T214314_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_0532_df-s04-d1_dw_0_20251101T191822_reco_stage1_20251101T214314_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_0532_df-s04-d1_dw_0_20251101T191822_reco_stage1_20251101T214314_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": 1762033395.0,
"core.end_time": 1762033395.0,
"core.events": [
49209,
49213,
49217,
49221,
49225,
49229,
49233,
49237,
49241,
49245,
49249,
49253,
49257,
49261,
49265,
49269,
49273,
49277,
49281,
49285,
49289,
49293,
49297
],
"core.event_count": 23,
"core.first_event_number": 49209,
"core.last_event_number": 49297,
"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_0532_df-s04-d1_dw_0_20251101T191822.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_0532_df-s04-d1_dw_0_20251101T191822_reco_stage1_20251101T214314_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": 1762033395.0,
"core.end_time": 1762033395.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_0532_df-s04-d1_dw_0_20251101T191822.hdf5"
}
]
} 