Jobsub ID 257089.101@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
EOS at call=97
[00:05:39.238] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=97 anode=0
[00:05:39.238] D [ glue ] <FrameFanin:nfsp> EOS at call=97 with 8
[00:05:39.238] D [ glue ] frame sink sees EOS
[00:05:39.238] D [ pgraph ] <Pgrapher:> graph execution complete
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.12 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.72 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.51 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.44 sec
[00:05:39.238] I [ timer ] Timer: WireCell::Aux::Resampler : 0.18 sec
[00:05:39.238] I [ timer ] Timer: WireCell::Aux::Resampler : 0.17 sec
[00:05:39.238] I [ timer ] Timer: WireCell::Aux::Resampler : 0.17 sec
[00:05:39.238] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[00:05:39.238] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[00:05:39.238] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[00:05:39.238] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:05:39.238] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[00:05:39.238] I [ timer ] Timer: Total node execution : 33.49999996833503 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25648 traces tagged "gauss"
FrameSaver: q=1.12326e+07 n=624675 tag=gauss
wclsFrameSaver: saving 32513 traces tagged "wiener"
FrameSaver: q=1.21666e+07 n=599669 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 256 XUs and 351 XVs -> 21 XUVs
C:0 T:1 697 XUs and 1081 XVs -> 23 XUVs
C:0 T:2 1348 XUs and 1504 XVs -> 88 XUVs
C:0 T:3 500 XUs and 624 XVs -> 46 XUVs
C:0 T:4 5946 XUs and 7637 XVs -> 358 XUVs
C:0 T:5 545 XUs and 1147 XVs -> 18 XUVs
C:0 T:6 10769 XUs and 13084 XVs -> 917 XUVs
C:0 T:7 8930 XUs and 10699 XVs -> 928 XUVs
2399 XUVs total
1323 collection wire objects
2399 potential space points
Neighbour search...
92801 tests to find 22498 neighbours
Iterating with no regularization...
Begin: 7.82184e+10
0 7.35413e+10
1 7.24422e+10
2 7.24395e+10
Now with regularization...
Begin: 7.16417e+10
0 7.16382e+10
04-Dec-2025 00:06:07 CET Closed output file "np02vd_raw_run041106_0753_df-s04-d0_dw_0_20251203T203940_reco_stage1_20251203T230607_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 48.467 107.367 782.719 75.4212 118.862 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 4.4631e-05 8.73757e-05 0.000233153 7.7611e-05 3.19117e-05 49
produce:tpcrawdecoder:PDVDTPCReader 1.4918 2.43017 5.1508 2.39583 0.617175 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.00570038 0.00956679 0.0328118 0.00807046 0.00503066 49
produce:pdvddaphne:DAPHNEReaderPDVD 0.000210913 0.000338662 0.000757421 0.000323594 7.37735e-05 49
produce:ophit:OpHitFinder 5.7851e-05 0.000100876 0.00064378 7.7661e-05 8.19653e-05 49
produce:opflash:OpFlashFinderVerticalDrift 4.563e-05 7.20479e-05 0.000430136 6.2431e-05 5.30391e-05 49
produce:wclsdatavd:WireCellToolkit 32.7151 44.8394 94.5684 41.7775 13.1876 49
produce:gaushit:GausHitFinder 0.273721 0.697894 1.7 0.631401 0.29061 49
produce:nhitsfilter:NumberOfHitsFilter 7.3161e-05 0.000159076 0.000502097 0.000142882 6.34359e-05 49
produce:reco3d:SpacePointSolver 2.04941 7.15219 15.8043 6.46834 3.20763 49
produce:hitpdune:DisambigFromSpacePoints 0.0143201 0.0900086 0.289592 0.07064 0.0609726 49
produce:pandora:StandardPandora 4.67968 49.791 664.125 23.8675 105.146 49
produce:pandoraTrack:LArPandoraTrackCreation 0.0817801 0.303489 0.981993 0.238335 0.20382 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00431902 0.0140843 0.0287579 0.0136928 0.00506969 49
[art]:TriggerResults:TriggerResultInserter 1.397e-05 2.17022e-05 7.0411e-05 1.911e-05 1.0086e-05 49
end_path:out1:RootOutput 5.58e-06 7.57722e-06 2.7941e-05 6.79e-06 3.83921e-06 49
end_path:out1:RootOutput(write) 1.52366 2.01954 2.76032 1.99745 0.334577 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 8286.11 MB
Peak resident set size usage (VmHWM): 6157.22 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run041106_0753_df-s04-d0_dw_0_20251203T203940_reco_stage1_20251203T230607_keepup.root
\tHists: np02vd_raw_run041106_0753_df-s04-d0_dw_0_20251203T203940_reco_stage1_20251203T230608_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run041106_0753_df-s04-d0_dw_0_20251203T203940_reco_stage1_20251203T230607_keepup.root
Ran successfully
{
"name": "np02vd_raw_run041106_0753_df-s04-d0_dw_0_20251203T203940_reco_stage1_20251203T230607_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_13_00d00",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1764803168.0,
"core.end_time": 1764803168.0,
"core.events": [
36778,
36779,
36780,
36781,
36782,
36783,
36784,
36785,
36786,
36787,
36788,
36789,
36790,
36791,
36792,
36793,
36794,
36795,
36796,
36797,
36798,
36799,
36800,
36801,
36802,
36803,
36804,
36805,
36806,
36807,
36808,
36809,
36810,
36811,
36812,
36813,
36814,
36815,
36816,
36817,
36818,
36819,
36820,
36821,
36822,
36823,
36824,
36825,
36826
],
"core.event_count": 49,
"core.first_event_number": 36778,
"core.last_event_number": 36826,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
41106
],
"core.runs_subruns": [
4110600001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run041106_0753_df-s04-d0_dw_0_20251203T203940.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run041106_0753_df-s04-d0_dw_0_20251203T203940_reco_stage1_20251203T230608_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_13_00d00",
"core.data_tier": "root-tuple-virtual",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1764803168.0,
"core.end_time": 1764803168.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
41106
],
"core.runs_subruns": [
4110600001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run041106_0753_df-s04-d0_dw_0_20251203T203940.hdf5"
}
]
} 