Jobsub ID 41786.2@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
8] D [ aux ] <Resampler:resmp0> EOS at call=95
[12:32:50.558] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=95 anode=0
[12:32:50.558] D [ glue ] <FrameFanin:nfsp> EOS at call=95 with 8
[12:32:50.558] D [ glue ] frame sink sees EOS
[12:32:50.558] D [ pgraph ] <Pgrapher:> graph execution complete
[12:32:50.558] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.28 sec
[12:32:50.558] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.18 sec
[12:32:50.558] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.83 sec
[12:32:50.558] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.82 sec
[12:32:50.558] I [ timer ] Timer: WireCell::Aux::Resampler : 0.42 sec
[12:32:50.558] I [ timer ] Timer: WireCell::Aux::Resampler : 0.41 sec
[12:32:50.558] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[12:32:50.558] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[12:32:50.558] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[12:32:50.558] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:32:50.558] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:32:50.558] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:32:50.559] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:32:50.559] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:32:50.559] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:32:50.559] I [ timer ] Timer: Total node execution : 25.700000116601586 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 20535 traces tagged "gauss"
FrameSaver: q=3.89258e+06 n=493173 tag=gauss
wclsFrameSaver: saving 25217 traces tagged "wiener"
FrameSaver: q=4.17868e+06 n=482346 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 5909 XUs and 8573 XVs -> 443 XUVs
C:0 T:1 1561 XUs and 1457 XVs -> 53 XUVs
C:0 T:2 583 XUs and 492 XVs -> 26 XUVs
C:0 T:3 1381 XUs and 1547 XVs -> 120 XUVs
C:0 T:4 273 XUs and 340 XVs -> 4 XUVs
C:0 T:5 148 XUs and 205 XVs -> 4 XUVs
C:0 T:6 930 XUs and 971 XVs -> 34 XUVs
C:0 T:7 761 XUs and 1081 XVs -> 18 XUVs
702 XUVs total
489 collection wire objects
702 potential space points
Neighbour search...
12440 tests to find 3614 neighbours
Iterating with no regularization...
Begin: 4.57203e+09
0 4.48167e+09
1 4.48116e+09
Now with regularization...
Begin: 4.36003e+09
0 4.35995e+09
18-Sep-2025 12:33:14 BST Closed output file "np02vd_raw_run039433_0307_df-s05-d4_dw_0_20250918T040000_reco_stage1_20250918T113314_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 78.0096 169.021 1284.38 116.902 230.017 48
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.8459e-05 0.000132335 0.00110668 9.603e-05 0.000154182 48
produce:tpcrawdecoder:PDVDTPCReader 29.8919 50.1028 74.6017 50.1744 10.7674 48
produce:triggerrawdecoder:PDVDTriggerReader4 0.375297 0.509134 1.06548 0.446419 0.141533 48
produce:pdvddaphne:DAPHNEReaderPDVD 6.21298 11.6981 16.8953 11.1865 2.75504 48
produce:ophit:OpHitFinder 0.0325568 0.049757 0.0665271 0.0502557 0.00636875 48
produce:opflash:OpFlashFinderVerticalDrift 0.00420706 0.010132 0.0192838 0.0101562 0.00333945 48
produce:wclsdatavd:WireCellToolkit 20.7194 30.0549 67.4236 25.9398 9.41986 48
produce:gaushit:GausHitFinder 0.24653 0.653043 2.2701 0.594486 0.316218 48
produce:nhitsfilter:NumberOfHitsFilter 8.1242e-05 0.000145993 0.000362661 0.000131888 6.10289e-05 48
produce:reco3d:SpacePointSolver 1.11254 5.80771 29.6605 4.4782 5.19476 48
produce:hitpdune:DisambigFromSpacePoints 0.0158084 0.107498 0.850611 0.0617799 0.146567 48
produce:pandora:StandardPandora 2.22972 67.0352 1145.56 12.6098 217.651 48
produce:pandoraTrack:LArPandoraTrackCreation 0.061571 0.404489 3.58477 0.2335 0.612891 48
produce:pandoraGnocalo:GnocchiCalorimetry 0.00378576 0.0169325 0.0614909 0.0149973 0.010101 48
[art]:TriggerResults:TriggerResultInserter 1.3966e-05 2.75752e-05 5.8399e-05 2.68805e-05 8.26443e-06 48
end_path:out1:RootOutput 3.116e-06 6.60971e-06 2.627e-05 6.412e-06 3.44805e-06 48
end_path:out1:RootOutput(write) 1.66129 2.55409 3.55101 2.5677 0.369969 48
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5574.12 MB
Peak resident set size usage (VmHWM): 3647.3 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0307_df-s05-d4_dw_0_20250918T040000_reco_stage1_20250918T113314_keepup.root
\tHists: np02vd_raw_run039433_0307_df-s05-d4_dw_0_20250918T040000_reco_stage1_20250918T113315_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0307_df-s05-d4_dw_0_20250918T040000_reco_stage1_20250918T113314_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0307_df-s05-d4_dw_0_20250918T040000_reco_stage1_20250918T113314_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": 1758195195.0,
"core.end_time": 1758195195.0,
"core.events": [
88006,
88012,
88018,
88024,
88030,
88036,
88042,
88048,
88054,
88060,
88066,
88072,
88078,
88084,
88090,
88096,
88102,
88108,
88114,
88120,
88126,
88132,
88138,
88144,
88150,
88156,
88162,
88168,
88174,
88180,
88186,
88192,
88198,
88204,
88210,
88216,
88222,
88228,
88234,
88240,
88246,
88252,
88258,
88264,
88270,
88276,
88282,
88288
],
"core.event_count": 48,
"core.first_event_number": 88006,
"core.last_event_number": 88288,
"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_0307_df-s05-d4_dw_0_20250918T040000.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0307_df-s05-d4_dw_0_20250918T040000_reco_stage1_20250918T113315_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": 1758195195.0,
"core.end_time": 1758195195.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_0307_df-s05-d4_dw_0_20250918T040000.hdf5"
}
]
} 