Jobsub ID 237693.130@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
5] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=47 anode=0
[12:19:52.836] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[12:19:52.836] D [ glue ] frame sink sees EOS
[12:19:52.836] D [ pgraph ] <Pgrapher:> graph execution complete
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.3 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.64 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.8 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.51 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.35 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.15 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.44 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.41 sec
[12:19:52.836] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[12:19:52.836] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[12:19:52.836] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[12:19:52.836] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[12:19:52.836] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:19:52.836] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:19:52.836] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:19:52.836] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:19:52.836] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:19:52.836] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:19:52.836] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:19:52.836] I [ timer ] Timer: Total node execution : 69.77000084519386 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 42021 traces tagged "gauss"
FrameSaver: q=1.02884e+07 n=889976 tag=gauss
wclsFrameSaver: saving 52321 traces tagged "wiener"
FrameSaver: q=1.13159e+07 n=855120 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 815 XUs and 800 XVs -> 27 XUVs
C:0 T:1 1683 XUs and 1532 XVs -> 67 XUVs
C:0 T:2 936 XUs and 759 XVs -> 27 XUVs
C:0 T:3 173 XUs and 224 XVs -> 14 XUVs
C:0 T:4 1165 XUs and 1644 XVs -> 65 XUVs
C:0 T:5 1493 XUs and 1833 XVs -> 67 XUVs
C:0 T:6 12634 XUs and 14311 XVs -> 1268 XUVs
C:0 T:7 5148 XUs and 5472 XVs -> 513 XUVs
C:0 T:8 554 XUs and 803 XVs -> 46 XUVs
C:0 T:9 355 XUs and 435 XVs -> 29 XUVs
C:0 T:10 87 XUs and 100 XVs -> 3 XUVs
C:0 T:11 1168 XUs and 1257 XVs -> 51 XUVs
C:0 T:12 864 XUs and 1105 XVs -> 90 XUVs
C:0 T:13 1158 XUs and 1342 XVs -> 65 XUVs
C:0 T:14 377 XUs and 516 XVs -> 49 XUVs
C:0 T:15 2011 XUs and 2227 XVs -> 121 XUVs
2502 XUVs total
1370 collection wire objects
2502 potential space points
Neighbour search...
120306 tests to find 28014 neighbours
Iterating with no regularization...
Begin: 3.42241e+09
0 3.08744e+09
1 3.06076e+09
2 3.05922e+09
Now with regularization...
Begin: 2.96225e+09
0 2.9617e+09
05-Nov-2025 12:21:06 CET Closed output file "np02vd_raw_run040361_0619_df-s04-d2_dw_0_20251105T053318_reco_stage1_20251105T112106_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 192.122 277.958 539.583 244.518 90.507 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.1137e-05 0.000109633 0.000359383 9.5099e-05 5.79647e-05 24
produce:tpcrawdecoder:PDVDTPCReader 79.5532 113.114 180.093 105.839 26.699 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.55619 0.864752 1.49788 0.836811 0.237519 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000305481 0.000522849 0.00154459 0.000468202 0.000227487 24
produce:ophit:OpHitFinder 6.2895e-05 0.000175344 0.00119974 0.00010607 0.000229589 24
produce:opflash:OpFlashFinderVerticalDrift 5.3941e-05 0.000131513 0.000666716 7.86885e-05 0.000130553 24
produce:wclsdatavd:WireCellToolkit 67.8276 91.8757 161.05 77.4509 28.449 24
produce:gaushit:GausHitFinder 0.767204 1.6251 2.53443 1.60202 0.436824 24
produce:nhitsfilter:NumberOfHitsFilter 0.000261134 0.00101136 0.00287684 0.000918171 0.000655166 24
produce:reco3d:SpacePointSolver 9.71669 15.0665 30.2598 13.7903 5.10898 24
produce:hitpdune:DisambigFromSpacePoints 0.122156 0.298871 0.941577 0.246906 0.184867 24
produce:pandora:StandardPandora 12.9267 45.4453 170.223 31.1749 39.3018 24
produce:pandoraTrack:LArPandoraTrackCreation 0.813969 2.84723 7.49245 2.74628 1.82774 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0285947 0.0485984 0.0880924 0.0452069 0.014447 24
[art]:TriggerResults:TriggerResultInserter 3.4542e-05 7.35694e-05 0.000128934 6.90045e-05 2.21647e-05 24
end_path:out1:RootOutput 5.097e-06 1.48575e-05 3.5103e-05 1.3551e-05 6.92692e-06 24
end_path:out1:RootOutput(write) 4.44654 6.63061 8.70836 6.70714 0.919494 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5269.18 MB
Peak resident set size usage (VmHWM): 3363.89 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040361_0619_df-s04-d2_dw_0_20251105T053318_reco_stage1_20251105T112106_keepup.root
\tHists: np02vd_raw_run040361_0619_df-s04-d2_dw_0_20251105T053318_reco_stage1_20251105T112107_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040361_0619_df-s04-d2_dw_0_20251105T053318_reco_stage1_20251105T112106_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040361_0619_df-s04-d2_dw_0_20251105T053318_reco_stage1_20251105T112106_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": 1762341668.0,
"core.end_time": 1762341668.0,
"core.events": [
57422,
57426,
57430,
57434,
57438,
57442,
57446,
57450,
57454,
57458,
57462,
57466,
57470,
57474,
57478,
57482,
57486,
57490,
57494,
57498,
57502,
57506,
57510,
57514
],
"core.event_count": 24,
"core.first_event_number": 57422,
"core.last_event_number": 57514,
"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_0619_df-s04-d2_dw_0_20251105T053318.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040361_0619_df-s04-d2_dw_0_20251105T053318_reco_stage1_20251105T112107_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": 1762341668.0,
"core.end_time": 1762341668.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_0619_df-s04-d2_dw_0_20251105T053318.hdf5"
}
]
} 