Jobsub ID 236578.191@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
8:28.663] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[12:18:28.663] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[12:18:28.663] D [ glue ] frame sink sees EOS
[12:18:28.663] D [ pgraph ] <Pgrapher:> graph execution complete
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.42 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.82 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.55 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.9 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.59 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.22 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.16 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.05 sec
[12:18:28.663] I [ timer ] Timer: WireCell::Aux::Resampler : 0.64 sec
[12:18:28.663] I [ timer ] Timer: WireCell::Aux::Resampler : 0.5 sec
[12:18:28.663] I [ timer ] Timer: WireCell::Aux::Resampler : 0.42 sec
[12:18:28.663] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[12:18:28.663] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[12:18:28.663] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:18:28.663] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:18:28.663] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:18:28.663] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:18:28.663] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:18:28.663] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:18:28.663] I [ timer ] Timer: Total node execution : 100.7199990618974 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 38478 traces tagged "gauss"
FrameSaver: q=1.27269e+07 n=850533 tag=gauss
wclsFrameSaver: saving 48937 traces tagged "wiener"
FrameSaver: q=1.35701e+07 n=810124 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 14919 XUs and 14495 XVs -> 955 XUVs
C:0 T:1 716 XUs and 1308 XVs -> 33 XUVs
C:0 T:2 8500 XUs and 7303 XVs -> 575 XUVs
C:0 T:3 545 XUs and 750 XVs -> 20 XUVs
C:0 T:4 472 XUs and 720 XVs -> 16 XUVs
C:0 T:5 222 XUs and 387 XVs -> 5 XUVs
C:0 T:6 65 XUs and 71 XVs -> 1 XUVs
C:0 T:7 357 XUs and 408 XVs -> 10 XUVs
C:0 T:8 1704 XUs and 1438 XVs -> 88 XUVs
C:0 T:9 28 XUs and 46 XVs -> 0 XUVs
C:0 T:10 427 XUs and 202 XVs -> 24 XUVs
C:0 T:11 505 XUs and 552 XVs -> 25 XUVs
C:0 T:12 588 XUs and 535 XVs -> 26 XUVs
C:0 T:13 3413 XUs and 4035 XVs -> 204 XUVs
C:0 T:14 518 XUs and 1263 XVs -> 39 XUVs
C:0 T:15 938 XUs and 1161 XVs -> 70 XUVs
2091 XUVs total
1397 collection wire objects
2091 potential space points
Neighbour search...
63429 tests to find 16714 neighbours
Iterating with no regularization...
Begin: 2.59368e+10
0 2.50755e+10
1 2.502e+10
2 2.50195e+10
Now with regularization...
Begin: 2.49208e+10
0 2.49207e+10
03-Nov-2025 12:19:11 CET Closed output file "np02vd_raw_run040346_3245_df-s04-d2_dw_0_20251103T092919_reco_stage1_20251103T111911_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 93.2037 148.551 441.684 120.463 75.6727 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.6541e-05 0.000102071 0.000179283 9.9841e-05 3.01422e-05 23
produce:tpcrawdecoder:PDVDTPCReader 12.6185 13.5717 15.3467 13.4098 0.612509 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.469657 0.488614 0.528731 0.487931 0.0168996 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000324805 0.000425229 0.00073873 0.000411515 7.94205e-05 23
produce:ophit:OpHitFinder 6.672e-05 0.000131837 0.000632968 0.000107441 0.000111544 23
produce:opflash:OpFlashFinderVerticalDrift 4.5991e-05 7.55593e-05 0.000317504 6.5171e-05 5.23512e-05 23
produce:wclsdatavd:WireCellToolkit 56.1996 72.6639 143.694 61.1387 20.2743 23
produce:gaushit:GausHitFinder 0.538039 0.977748 1.88413 0.933668 0.301672 23
produce:nhitsfilter:NumberOfHitsFilter 0.000176653 0.000282762 0.000537878 0.000260663 9.75433e-05 23
produce:reco3d:SpacePointSolver 5.33479 11.3218 28.4501 11.0594 5.1528 23
produce:hitpdune:DisambigFromSpacePoints 0.0849401 0.19582 0.643343 0.153526 0.126744 23
produce:pandora:StandardPandora 8.91112 42.631 298.568 22.571 59.4187 23
produce:pandoraTrack:LArPandoraTrackCreation 0.305876 0.940055 4.38955 0.682947 0.817021 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0167329 0.0284801 0.0463185 0.0285496 0.0084184 23
[art]:TriggerResults:TriggerResultInserter 1.33e-05 2.25347e-05 5.2871e-05 1.913e-05 9.71756e-06 23
end_path:out1:RootOutput 3.24e-06 8.31748e-06 3.145e-05 6.701e-06 6.39875e-06 23
end_path:out1:RootOutput(write) 4.41431 5.69666 7.76329 5.60053 0.805254 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6131.22 MB
Peak resident set size usage (VmHWM): 4041.52 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3245_df-s04-d2_dw_0_20251103T092919_reco_stage1_20251103T111911_keepup.root
\tHists: np02vd_raw_run040346_3245_df-s04-d2_dw_0_20251103T092919_reco_stage1_20251103T111911_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3245_df-s04-d2_dw_0_20251103T092919_reco_stage1_20251103T111911_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3245_df-s04-d2_dw_0_20251103T092919_reco_stage1_20251103T111911_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": 1762168753.0,
"core.end_time": 1762168753.0,
"core.events": [
299610,
299614,
299618,
299622,
299626,
299630,
299634,
299638,
299642,
299646,
299650,
299654,
299658,
299662,
299666,
299670,
299674,
299678,
299682,
299686,
299690,
299694,
299698
],
"core.event_count": 23,
"core.first_event_number": 299610,
"core.last_event_number": 299698,
"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_3245_df-s04-d2_dw_0_20251103T092919.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3245_df-s04-d2_dw_0_20251103T092919_reco_stage1_20251103T111911_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": 1762168753.0,
"core.end_time": 1762168753.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_3245_df-s04-d2_dw_0_20251103T092919.hdf5"
}
]
} 