Jobsub ID 242471.125@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
igproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[20:30:33.625] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[20:30:33.625] D [ glue ] frame sink sees EOS
[20:30:33.625] D [ pgraph ] <Pgrapher:> graph execution complete
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.7 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.02 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.81 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.55 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.5 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.27 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.23 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.6 sec
[20:30:33.625] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[20:30:33.625] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[20:30:33.625] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[20:30:33.625] I [ timer ] Timer: WireCell::Aux::Resampler : 0.22 sec
[20:30:33.625] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[20:30:33.625] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:30:33.625] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:30:33.625] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:30:33.625] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[20:30:33.625] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[20:30:33.625] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[20:30:33.625] I [ timer ] Timer: Total node execution : 54.62999982200563 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 54510 traces tagged "gauss"
FrameSaver: q=1.32862e+07 n=1181163 tag=gauss
wclsFrameSaver: saving 67879 traces tagged "wiener"
FrameSaver: q=1.42235e+07 n=1127901 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 546 XUs and 629 XVs -> 28 XUVs
C:0 T:1 950 XUs and 1074 XVs -> 44 XUVs
C:0 T:2 1432 XUs and 1323 XVs -> 49 XUVs
C:0 T:3 29984 XUs and 26172 XVs -> 1678 XUVs
C:0 T:4 25 XUs and 29 XVs -> 0 XUVs
C:0 T:5 286 XUs and 311 XVs -> 9 XUVs
C:0 T:6 564 XUs and 514 XVs -> 17 XUVs
C:0 T:7 1265 XUs and 1435 XVs -> 41 XUVs
C:0 T:8 1232 XUs and 1191 XVs -> 67 XUVs
C:0 T:9 1310 XUs and 1450 XVs -> 81 XUVs
C:0 T:10 1877 XUs and 1265 XVs -> 55 XUVs
C:0 T:11 1793 XUs and 2002 XVs -> 118 XUVs
C:0 T:12 1687 XUs and 1701 XVs -> 94 XUVs
C:0 T:13 630 XUs and 614 XVs -> 29 XUVs
C:0 T:14 237 XUs and 329 XVs -> 23 XUVs
C:0 T:15 4199 XUs and 4107 XVs -> 158 XUVs
2491 XUVs total
1641 collection wire objects
2491 potential space points
Neighbour search...
48247 tests to find 11580 neighbours
Iterating with no regularization...
Begin: 3.72599e+09
0 3.5315e+09
1 3.52559e+09
2 3.52547e+09
Now with regularization...
Begin: 3.48119e+09
0 3.48113e+09
03-Nov-2025 20:31:35 GMT Closed output file "np02vd_raw_run040346_3763_df-s04-d3_dw_0_20251103T170717_reco_stage1_20251103T203135_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 289.679 406.94 589.553 390.513 85.5759 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 4.2583e-05 6.2899e-05 0.000101691 5.9888e-05 1.35241e-05 23
produce:tpcrawdecoder:PDVDTPCReader 195.081 289.333 456.745 277.401 72.9477 23
produce:triggerrawdecoder:PDVDTriggerReader4 1.17188 1.73604 3.33226 1.72446 0.438489 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000335343 0.000370584 0.000623648 0.000357808 5.54399e-05 23
produce:ophit:OpHitFinder 4.6703e-05 7.4527e-05 0.000442242 5.6984e-05 7.87441e-05 23
produce:opflash:OpFlashFinderVerticalDrift 3.7144e-05 5.14993e-05 0.000238719 4.1825e-05 4.02931e-05 23
produce:wclsdatavd:WireCellToolkit 52.4395 71.5949 101.394 64.6289 15.731 23
produce:gaushit:GausHitFinder 0.848885 1.18791 1.51451 1.18502 0.194772 23
produce:nhitsfilter:NumberOfHitsFilter 0.000258666 0.000323991 0.000458454 0.000301453 4.97207e-05 23
produce:reco3d:SpacePointSolver 7.32602 10.2999 14.7611 10.2284 2.16478 23
produce:hitpdune:DisambigFromSpacePoints 0.108875 0.178141 0.29757 0.175584 0.0511862 23
produce:pandora:StandardPandora 16.1675 27.5873 56.3758 23.3434 10.6083 23
produce:pandoraTrack:LArPandoraTrackCreation 0.595326 1.0641 2.0044 0.931451 0.384305 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0191583 0.0258363 0.0336049 0.0253378 0.00378328 23
[art]:TriggerResults:TriggerResultInserter 1.6345e-05 2.20466e-05 4.7058e-05 2.027e-05 6.21589e-06 23
end_path:out1:RootOutput 6.785e-06 8.4277e-06 1.9427e-05 7.71e-06 2.65795e-06 23
end_path:out1:RootOutput(write) 3.53351 3.90198 4.64851 3.77495 0.340199 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5679.21 MB
Peak resident set size usage (VmHWM): 3634.76 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3763_df-s04-d3_dw_0_20251103T170717_reco_stage1_20251103T203135_keepup.root
\tHists: np02vd_raw_run040346_3763_df-s04-d3_dw_0_20251103T170717_reco_stage1_20251103T203143_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3763_df-s04-d3_dw_0_20251103T170717_reco_stage1_20251103T203135_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3763_df-s04-d3_dw_0_20251103T170717_reco_stage1_20251103T203135_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": 1762201903.0,
"core.end_time": 1762201903.0,
"core.events": [
347175,
347179,
347183,
347187,
347191,
347195,
347199,
347203,
347207,
347211,
347215,
347219,
347223,
347227,
347231,
347235,
347239,
347243,
347247,
347251,
347255,
347259,
347263
],
"core.event_count": 23,
"core.first_event_number": 347175,
"core.last_event_number": 347263,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3763_df-s04-d3_dw_0_20251103T170717.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3763_df-s04-d3_dw_0_20251103T170717_reco_stage1_20251103T203143_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": 1762201903.0,
"core.end_time": 1762201903.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3763_df-s04-d3_dw_0_20251103T170717.hdf5"
}
]
} 