Jobsub ID 48994.49@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
c:anode1sigproc1> EOS at call=79 anode=1
[22:51:39.899] D [ glue ] <ChannelSelector:chsel0> see EOS at call=39
[22:51:39.899] D [ aux ] <Resampler:resmp0> EOS at call=79
[22:51:39.899] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[22:51:39.899] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[22:51:39.899] D [ glue ] frame sink sees EOS
[22:51:39.899] D [ pgraph ] <Pgrapher:> graph execution complete
[22:51:39.899] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.2 sec
[22:51:39.899] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.78 sec
[22:51:39.899] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.76 sec
[22:51:39.899] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.46 sec
[22:51:39.899] I [ timer ] Timer: WireCell::Aux::Resampler : 0.39 sec
[22:51:39.899] I [ timer ] Timer: WireCell::Aux::Resampler : 0.34 sec
[22:51:39.899] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[22:51:39.899] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[22:51:39.900] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[22:51:39.900] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[22:51:39.900] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[22:51:39.900] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:51:39.900] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[22:51:39.900] I [ timer ] Timer: Total node execution : 39.61999982222915 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25097 traces tagged "gauss"
FrameSaver: q=7.18852e+06 n=639163 tag=gauss
wclsFrameSaver: saving 30693 traces tagged "wiener"
FrameSaver: q=7.66181e+06 n=622858 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 506 XUs and 383 XVs -> 25 XUVs
C:0 T:1 532 XUs and 632 XVs -> 41 XUVs
C:0 T:2 887 XUs and 947 XVs -> 36 XUVs
C:0 T:3 1298 XUs and 1470 XVs -> 55 XUVs
C:0 T:4 1022 XUs and 1211 XVs -> 32 XUVs
C:0 T:5 14332 XUs and 20005 XVs -> 1103 XUVs
C:0 T:6 2331 XUs and 2131 XVs -> 86 XUVs
C:0 T:7 318 XUs and 242 XVs -> 13 XUVs
1391 XUVs total
877 collection wire objects
1391 potential space points
Neighbour search...
38609 tests to find 10050 neighbours
Iterating with no regularization...
Begin: 6.97416e+10
0 6.95245e+10
1 6.95205e+10
Now with regularization...
Begin: 6.85345e+10
0 6.8534e+10
18-Sep-2025 22:52:21 BST Closed output file "np02vd_raw_run039499_0023_df-s04-d2_dw_0_20250918T182758_reco_stage1_20250918T215221_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 67.9177 177.092 1401.32 113.335 268.064 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.1963e-05 0.000157957 0.000300315 0.00015345 4.63827e-05 40
produce:tpcrawdecoder:PDVDTPCReader 16.7722 35.742 66.527 34.086 10.253 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.376857 0.431576 0.60298 0.409496 0.0554752 40
produce:pdvddaphne:DAPHNEReaderPDVD 4.07479 8.13449 12.0434 8.45582 2.04163 40
produce:ophit:OpHitFinder 0.0454407 0.0613856 0.0964937 0.059853 0.0103238 40
produce:opflash:OpFlashFinderVerticalDrift 0.006927 0.0147136 0.0296977 0.0137903 0.00429003 40
produce:wclsdatavd:WireCellToolkit 27.5881 39.9163 59.1533 36.4958 8.09598 40
produce:gaushit:GausHitFinder 0.195624 0.752696 1.85338 0.728774 0.2588 40
produce:nhitsfilter:NumberOfHitsFilter 7.6845e-05 0.000204412 0.000515721 0.000186831 8.08575e-05 40
produce:reco3d:SpacePointSolver 1.06976 6.49007 22.8673 5.27515 3.98047 40
produce:hitpdune:DisambigFromSpacePoints 0.00801653 0.113133 0.709973 0.0700125 0.132555 40
produce:pandora:StandardPandora 1.53216 81.8155 1291.12 16.9431 262.514 40
produce:pandoraTrack:LArPandoraTrackCreation 0.0475039 0.424931 3.75457 0.306599 0.575104 40
produce:pandoraGnocalo:GnocchiCalorimetry 0.00598547 0.0191235 0.0585556 0.0186627 0.00889723 40
[art]:TriggerResults:TriggerResultInserter 1.9377e-05 3.9256e-05 8.0341e-05 3.6394e-05 1.30164e-05 40
end_path:out1:RootOutput 4.027e-06 8.45562e-06 3.2952e-05 7.3535e-06 4.71121e-06 40
end_path:out1:RootOutput(write) 2.19251 3.14823 4.05636 3.13507 0.432317 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5166.97 MB
Peak resident set size usage (VmHWM): 3233.71 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0023_df-s04-d2_dw_0_20250918T182758_reco_stage1_20250918T215221_keepup.root
\tHists: np02vd_raw_run039499_0023_df-s04-d2_dw_0_20250918T182758_reco_stage1_20250918T215222_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0023_df-s04-d2_dw_0_20250918T182758_reco_stage1_20250918T215221_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0023_df-s04-d2_dw_0_20250918T182758_reco_stage1_20250918T215221_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_10_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": 1758232343.0,
"core.end_time": 1758232343.0,
"core.events": [
5599,
5605,
5611,
5617,
5623,
5629,
5635,
5641,
5647,
5653,
5659,
5665,
5671,
5677,
5683,
5689,
5695,
5701,
5707,
5713,
5719,
5725,
5731,
5737,
5743,
5749,
5755,
5761,
5767,
5773,
5779,
5785,
5791,
5797,
5803,
5809,
5815,
5821,
5827,
5833
],
"core.event_count": 40,
"core.first_event_number": 5599,
"core.last_event_number": 5833,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0023_df-s04-d2_dw_0_20250918T182758.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0023_df-s04-d2_dw_0_20250918T182758_reco_stage1_20250918T215222_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_10_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": 1758232343.0,
"core.end_time": 1758232343.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0023_df-s04-d2_dw_0_20250918T182758.hdf5"
}
]
} 