Jobsub ID 243389.109@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
12:16:47.017] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=47 anode=0
[12:16:47.017] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[12:16:47.017] D [ glue ] frame sink sees EOS
[12:16:47.017] D [ pgraph ] <Pgrapher:> graph execution complete
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 36.29 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 20.22 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.23 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.4 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.39 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.37 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.02 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.66 sec
[12:16:47.018] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[12:16:47.018] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[12:16:47.018] I [ timer ] Timer: WireCell::Aux::Resampler : 0.34 sec
[12:16:47.018] I [ timer ] Timer: WireCell::Aux::Resampler : 0.34 sec
[12:16:47.018] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:16:47.018] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:16:47.018] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:16:47.018] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:16:47.018] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[12:16:47.018] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:16:47.018] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:16:47.018] I [ timer ] Timer: Total node execution : 150.01999993808568 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 49682 traces tagged "gauss"
FrameSaver: q=9.51874e+07 n=1757843 tag=gauss
wclsFrameSaver: saving 61603 traces tagged "wiener"
FrameSaver: q=9.68248e+07 n=1708894 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 154 XUs and 142 XVs -> 13 XUVs
C:0 T:1 324 XUs and 424 XVs -> 7 XUVs
C:0 T:2 57 XUs and 54 XVs -> 0 XUVs
C:0 T:3 473 XUs and 537 XVs -> 14 XUVs
C:0 T:4 29519 XUs and 41114 XVs -> 2571 XUVs
C:0 T:5 407 XUs and 453 XVs -> 12 XUVs
C:0 T:6 4410 XUs and 4816 XVs -> 233 XUVs
C:0 T:7 451 XUs and 680 XVs -> 22 XUVs
C:0 T:8 268 XUs and 413 XVs -> 22 XUVs
C:0 T:9 871 XUs and 1096 XVs -> 50 XUVs
C:0 T:10 1611 XUs and 2428 XVs -> 71 XUVs
C:0 T:11 1725 XUs and 1994 XVs -> 60 XUVs
C:0 T:12 15299 XUs and 35902 XVs -> 1332 XUVs
C:0 T:13 2200 XUs and 1114 XVs -> 181 XUVs
C:0 T:14 1108 XUs and 1408 XVs -> 82 XUVs
C:0 T:15 646 XUs and 676 XVs -> 38 XUVs
4708 XUVs total
2458 collection wire objects
4708 potential space points
Neighbour search...
136214 tests to find 33320 neighbours
Iterating with no regularization...
Begin: 1.70237e+12
0 1.69735e+12
1 1.69705e+12
Now with regularization...
Begin: 1.69358e+12
0 1.69358e+12
05-Nov-2025 12:18:52 CET Closed output file "np02vd_raw_run040361_0612_df-s04-d1_dw_0_20251105T052625_reco_stage1_20251105T111852_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 193.206 278.624 548.986 233.718 89.9685 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.3037e-05 9.40962e-05 0.000238407 8.527e-05 3.11685e-05 24
produce:tpcrawdecoder:PDVDTPCReader 75.3246 100.434 161.609 89.9322 24.0526 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.52832 0.74201 1.43209 0.68347 0.207775 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.00033552 0.000569752 0.0011018 0.000522415 0.000155022 24
produce:ophit:OpHitFinder 6.1115e-05 0.000109268 0.000821371 8.13725e-05 0.000148742 24
produce:opflash:OpFlashFinderVerticalDrift 4.5015e-05 8.18336e-05 0.000482405 6.2472e-05 8.50001e-05 24
produce:wclsdatavd:WireCellToolkit 61.8547 98.7395 161.947 82.0438 31.6771 24
produce:gaushit:GausHitFinder 1.14734 1.77543 3.21795 1.62072 0.47397 24
produce:nhitsfilter:NumberOfHitsFilter 0.000261971 0.000468705 0.000679656 0.000438087 0.000111011 24
produce:reco3d:SpacePointSolver 7.32804 15.5602 31.2189 13.9835 5.58391 24
produce:hitpdune:DisambigFromSpacePoints 0.115192 0.258996 0.594589 0.195036 0.120249 24
produce:pandora:StandardPandora 23.202 52.2142 252.523 31.1009 49.8072 24
produce:pandoraTrack:LArPandoraTrackCreation 0.622381 1.67469 5.29365 1.38941 1.08756 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0231199 0.0528785 0.089179 0.0512731 0.0148689 24
[art]:TriggerResults:TriggerResultInserter 2.172e-05 4.4896e-05 0.000125275 4.0391e-05 1.97268e-05 24
end_path:out1:RootOutput 3.997e-06 7.21858e-06 2.8403e-05 5.7955e-06 4.76883e-06 24
end_path:out1:RootOutput(write) 5.89087 7.10493 9.64514 6.74217 1.00705 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5875.52 MB
Peak resident set size usage (VmHWM): 3978.62 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040361_0612_df-s04-d1_dw_0_20251105T052625_reco_stage1_20251105T111852_keepup.root
\tHists: np02vd_raw_run040361_0612_df-s04-d1_dw_0_20251105T052625_reco_stage1_20251105T111852_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040361_0612_df-s04-d1_dw_0_20251105T052625_reco_stage1_20251105T111852_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040361_0612_df-s04-d1_dw_0_20251105T052625_reco_stage1_20251105T111852_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_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": 1762341533.0,
"core.end_time": 1762341533.0,
"core.events": [
56661,
56665,
56669,
56673,
56677,
56681,
56685,
56689,
56693,
56697,
56701,
56705,
56709,
56713,
56717,
56721,
56725,
56729,
56733,
56737,
56741,
56745,
56749,
56753
],
"core.event_count": 24,
"core.first_event_number": 56661,
"core.last_event_number": 56753,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40361
],
"core.runs_subruns": [
4036100001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040361_0612_df-s04-d1_dw_0_20251105T052625.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040361_0612_df-s04-d1_dw_0_20251105T052625_reco_stage1_20251105T111852_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_11_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": 1762341533.0,
"core.end_time": 1762341533.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40361
],
"core.runs_subruns": [
4036100001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040361_0612_df-s04-d1_dw_0_20251105T052625.hdf5"
}
]
} 