Jobsub ID 42699.78@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
4:00.490] D [ glue ] <ChannelSelector:chsel0> see EOS at call=40
[04:24:00.490] D [ aux ] <Resampler:resmp0> EOS at call=81
[04:24:00.490] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0
[04:24:00.490] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[04:24:00.490] D [ glue ] frame sink sees EOS
[04:24:00.490] D [ pgraph ] <Pgrapher:> graph execution complete
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 20.56 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 20.24 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.52 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 18.17 sec
[04:24:00.490] I [ timer ] Timer: WireCell::Aux::Resampler : 0.42 sec
[04:24:00.490] I [ timer ] Timer: WireCell::Aux::Resampler : 0.42 sec
[04:24:00.490] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[04:24:00.490] I [ timer ] Timer: WireCell::Aux::Resampler : 0.39 sec
[04:24:00.490] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[04:24:00.490] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[04:24:00.490] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[04:24:00.490] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[04:24:00.490] I [ timer ] Timer: Total node execution : 80.13999973610044 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 20343 traces tagged "gauss"
FrameSaver: q=5.03495e+06 n=495011 tag=gauss
wclsFrameSaver: saving 26623 traces tagged "wiener"
FrameSaver: q=5.57243e+06 n=476558 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 28 XUs and 27 XVs -> 1 XUVs
C:0 T:1 178 XUs and 243 XVs -> 4 XUVs
C:0 T:2 524 XUs and 570 XVs -> 21 XUVs
C:0 T:3 416 XUs and 570 XVs -> 33 XUVs
C:0 T:4 104 XUs and 95 XVs -> 6 XUVs
C:0 T:5 17694 XUs and 20668 XVs -> 1949 XUVs
C:0 T:6 833 XUs and 1583 XVs -> 239 XUVs
C:0 T:7 11034 XUs and 11097 XVs -> 853 XUVs
3106 XUVs total
1338 collection wire objects
3106 potential space points
Neighbour search...
174902 tests to find 52084 neighbours
Iterating with no regularization...
Begin: 1.20711e+10
0 1.1905e+10
1 1.18958e+10
Now with regularization...
Begin: 1.16082e+10
0 1.16077e+10
19-Sep-2025 04:25:46 BST Closed output file "np02vd_raw_run039499_0133_df-s04-d1_dw_0_20250918T220932_reco_stage1_20250919T032545_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 110.058 219.368 1597.67 150.174 232.505 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.8868e-05 0.000105367 0.00041465 9.6431e-05 5.00528e-05 41
produce:tpcrawdecoder:PDVDTPCReader 10.6215 41.6856 111.933 40.6136 15.1077 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.379326 0.448688 1.13159 0.4107 0.12383 41
produce:pdvddaphne:DAPHNEReaderPDVD 6.3311 9.95265 22.2242 9.57348 2.60706 41
produce:ophit:OpHitFinder 0.0531676 0.0723504 0.108671 0.0714243 0.0101534 41
produce:opflash:OpFlashFinderVerticalDrift 0.0109949 0.0197317 0.0363948 0.0186548 0.00585114 41
produce:wclsdatavd:WireCellToolkit 43.9115 62.2048 119.565 54.3152 17.0408 41
produce:gaushit:GausHitFinder 0.522762 1.08206 2.12349 1.0569 0.368172 41
produce:nhitsfilter:NumberOfHitsFilter 0.000117121 0.000251879 0.000662105 0.000230293 0.000107565 41
produce:reco3d:SpacePointSolver 2.44534 10.5948 39.2285 8.49538 7.04476 41
produce:hitpdune:DisambigFromSpacePoints 0.0422533 0.175163 0.867554 0.116457 0.162291 41
produce:pandora:StandardPandora 8.79035 88.2303 1448.49 31.0683 223.049 41
produce:pandoraTrack:LArPandoraTrackCreation 0.199235 0.664502 3.03144 0.481382 0.575542 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.0142594 0.0290917 0.0566263 0.0260004 0.00998113 41
[art]:TriggerResults:TriggerResultInserter 2.1992e-05 3.25018e-05 0.000137939 2.8283e-05 1.94146e-05 41
end_path:out1:RootOutput 4.248e-06 5.90668e-06 3.3273e-05 5.24e-06 4.3629e-06 41
end_path:out1:RootOutput(write) 3.65876 4.18206 4.99466 4.20892 0.372773 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4892.82 MB
Peak resident set size usage (VmHWM): 2962.86 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0133_df-s04-d1_dw_0_20250918T220932_reco_stage1_20250919T032545_keepup.root
\tHists: np02vd_raw_run039499_0133_df-s04-d1_dw_0_20250918T220932_reco_stage1_20250919T032546_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0133_df-s04-d1_dw_0_20250918T220932_reco_stage1_20250919T032545_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0133_df-s04-d1_dw_0_20250918T220932_reco_stage1_20250919T032545_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": 1758252347.0,
"core.end_time": 1758252347.0,
"core.events": [
32430,
32436,
32442,
32448,
32454,
32460,
32466,
32472,
32478,
32484,
32490,
32496,
32502,
32508,
32514,
32520,
32526,
32532,
32538,
32544,
32550,
32556,
32562,
32568,
32574,
32580,
32586,
32592,
32598,
32604,
32610,
32616,
32622,
32628,
32634,
32640,
32646,
32652,
32658,
32664,
32670
],
"core.event_count": 41,
"core.first_event_number": 32430,
"core.last_event_number": 32670,
"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_0133_df-s04-d1_dw_0_20250918T220932.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0133_df-s04-d1_dw_0_20250918T220932_reco_stage1_20250919T032546_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": 1758252347.0,
"core.end_time": 1758252347.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_0133_df-s04-d1_dw_0_20250918T220932.hdf5"
}
]
} 