Jobsub ID 41739.1@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
at call=93
[12:42:05.520] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=93 anode=0
[12:42:05.520] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[12:42:05.520] D [ glue ] frame sink sees EOS
[12:42:05.520] D [ pgraph ] <Pgrapher:> graph execution complete
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.62 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.89 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.82 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.52 sec
[12:42:05.520] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[12:42:05.520] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[12:42:05.520] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[12:42:05.520] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[12:42:05.520] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:42:05.520] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:42:05.520] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:42:05.520] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:42:05.520] I [ timer ] Timer: Total node execution : 44.500000366941094 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 17263 traces tagged "gauss"
FrameSaver: q=3.30873e+06 n=397605 tag=gauss
wclsFrameSaver: saving 21594 traces tagged "wiener"
FrameSaver: q=3.519e+06 n=381442 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 408 XUs and 507 XVs -> 35 XUVs
C:0 T:1 649 XUs and 785 XVs -> 41 XUVs
C:0 T:2 2545 XUs and 3219 XVs -> 172 XUVs
C:0 T:3 6066 XUs and 6857 XVs -> 506 XUVs
C:0 T:4 133 XUs and 156 XVs -> 8 XUVs
C:0 T:5 1469 XUs and 1465 XVs -> 90 XUVs
C:0 T:6 95 XUs and 124 XVs -> 9 XUVs
C:0 T:7 726 XUs and 505 XVs -> 23 XUVs
884 XUVs total
596 collection wire objects
884 potential space points
Neighbour search...
13878 tests to find 4072 neighbours
Iterating with no regularization...
Begin: 7.56214e+09
0 7.52214e+09
1 7.52194e+09
Now with regularization...
Begin: 7.33347e+09
0 7.33342e+09
18-Sep-2025 12:42:19 CEST Closed output file "np02vd_raw_run039433_0378_df-s05-d1_dw_0_20250918T065213_reco_stage1_20250918T104219_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 75.4221 217.652 5297.43 99.1194 750.178 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.1131e-05 8.61362e-05 0.000271349 7.5633e-05 3.9575e-05 47
produce:tpcrawdecoder:PDVDTPCReader 22.695 42.1275 83.3874 40.2581 11.6068 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.304587 0.393881 0.64504 0.360189 0.0858016 47
produce:pdvddaphne:DAPHNEReaderPDVD 5.53562 9.83638 14.0877 9.55213 1.96177 47
produce:ophit:OpHitFinder 0.0253796 0.037335 0.0462448 0.0385881 0.00529062 47
produce:opflash:OpFlashFinderVerticalDrift 0.00251166 0.00709047 0.0177714 0.00696339 0.00265853 47
produce:wclsdatavd:WireCellToolkit 19.2165 30.9787 46.9659 27.7435 8.07059 47
produce:gaushit:GausHitFinder 0.224672 0.454443 1.06723 0.443564 0.150423 47
produce:nhitsfilter:NumberOfHitsFilter 7.1629e-05 0.000160707 0.000560575 0.0001192 0.000106446 47
produce:reco3d:SpacePointSolver 1.29098 4.60942 21.812 4.12494 3.19606 47
produce:hitpdune:DisambigFromSpacePoints 0.0127838 0.059338 0.512618 0.037591 0.0813752 47
produce:pandora:StandardPandora 2.23804 126.872 5190.39 9.31633 747.36 47
produce:pandoraTrack:LArPandoraTrackCreation 0.0380879 0.287536 2.89745 0.174472 0.45077 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00475008 0.00992143 0.0182339 0.00958635 0.00316138 47
[art]:TriggerResults:TriggerResultInserter 1.8128e-05 2.74626e-05 5.4932e-05 2.5769e-05 8.62993e-06 47
end_path:out1:RootOutput 4.236e-06 5.58287e-06 1.7216e-05 5.028e-06 2.07616e-06 47
end_path:out1:RootOutput(write) 1.64565 1.93985 2.43357 1.87435 0.227964 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4650.13 MB
Peak resident set size usage (VmHWM): 2759.75 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0378_df-s05-d1_dw_0_20250918T065213_reco_stage1_20250918T104219_keepup.root
\tHists: np02vd_raw_run039433_0378_df-s05-d1_dw_0_20250918T065213_reco_stage1_20250918T104219_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0378_df-s05-d1_dw_0_20250918T065213_reco_stage1_20250918T104219_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0378_df-s05-d1_dw_0_20250918T065213_reco_stage1_20250918T104219_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": 1758192139.0,
"core.end_time": 1758192139.0,
"core.events": [
108373,
108379,
108385,
108391,
108397,
108403,
108409,
108415,
108421,
108427,
108433,
108439,
108445,
108451,
108457,
108463,
108469,
108475,
108481,
108487,
108493,
108499,
108505,
108511,
108517,
108523,
108529,
108535,
108541,
108547,
108553,
108559,
108565,
108571,
108577,
108583,
108589,
108595,
108601,
108607,
108613,
108619,
108625,
108631,
108637,
108643,
108649
],
"core.event_count": 47,
"core.first_event_number": 108373,
"core.last_event_number": 108649,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0378_df-s05-d1_dw_0_20250918T065213.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0378_df-s05-d1_dw_0_20250918T065213_reco_stage1_20250918T104219_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": 1758192139.0,
"core.end_time": 1758192139.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0378_df-s05-d1_dw_0_20250918T065213.hdf5"
}
]
} 