Jobsub ID 237725.1@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
all=47
[13:35:23.467] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=47 anode=0
[13:35:23.467] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[13:35:23.467] D [ glue ] frame sink sees EOS
[13:35:23.467] D [ pgraph ] <Pgrapher:> graph execution complete
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.81 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.13 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.11 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.74 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.54 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.5 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.37 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.14 sec
[13:35:23.467] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[13:35:23.467] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[13:35:23.467] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[13:35:23.467] I [ timer ] Timer: WireCell::Aux::Resampler : 0.23 sec
[13:35:23.467] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:35:23.467] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:35:23.467] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:35:23.467] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[13:35:23.467] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:35:23.467] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:35:23.467] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:35:23.467] I [ timer ] Timer: Total node execution : 79.32000014930964 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 40751 traces tagged "gauss"
FrameSaver: q=9.81116e+06 n=817081 tag=gauss
wclsFrameSaver: saving 50442 traces tagged "wiener"
FrameSaver: q=1.0585e+07 n=776729 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1118 XUs and 1089 XVs -> 28 XUVs
C:0 T:1 405 XUs and 617 XVs -> 27 XUVs
C:0 T:2 12740 XUs and 14605 XVs -> 903 XUVs
C:0 T:3 628 XUs and 1605 XVs -> 28 XUVs
C:0 T:4 314 XUs and 467 XVs -> 15 XUVs
C:0 T:5 961 XUs and 1058 XVs -> 42 XUVs
C:0 T:6 1017 XUs and 986 XVs -> 39 XUVs
C:0 T:7 683 XUs and 618 XVs -> 33 XUVs
C:0 T:8 1171 XUs and 1184 XVs -> 37 XUVs
C:0 T:9 152 XUs and 344 XVs -> 7 XUVs
C:0 T:10 448 XUs and 505 XVs -> 30 XUVs
C:0 T:11 472 XUs and 722 XVs -> 31 XUVs
C:0 T:12 663 XUs and 829 XVs -> 28 XUVs
C:0 T:13 789 XUs and 893 XVs -> 77 XUVs
C:0 T:14 353 XUs and 677 XVs -> 24 XUVs
C:0 T:15 559 XUs and 555 XVs -> 37 XUVs
1386 XUVs total
925 collection wire objects
1386 potential space points
Neighbour search...
32348 tests to find 8242 neighbours
Iterating with no regularization...
Begin: 7.64337e+09
0 7.32928e+09
1 7.31992e+09
2 7.31984e+09
Now with regularization...
Begin: 7.23606e+09
0 7.23592e+09
05-Nov-2025 13:36:00 CET Closed output file "np02vd_raw_run040361_0555_df-s04-d1_dw_0_20251105T043610_reco_stage1_20251105T123600_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 150.631 288.135 1115.03 227.164 219.753 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.3836e-05 8.16157e-05 0.000219589 7.63235e-05 2.95849e-05 24
produce:tpcrawdecoder:PDVDTPCReader 68.3425 103.541 156.218 99.3795 23.6452 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.588852 0.861151 1.28463 0.86066 0.171978 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000262473 0.00035058 0.000604293 0.000344025 7.03531e-05 24
produce:ophit:OpHitFinder 6.1713e-05 9.84028e-05 0.000501692 8.0911e-05 8.51427e-05 24
produce:opflash:OpFlashFinderVerticalDrift 3.9678e-05 6.68941e-05 0.000271767 5.8918e-05 4.39047e-05 24
produce:wclsdatavd:WireCellToolkit 44.2447 77.7113 156.782 76.5439 24.3916 24
produce:gaushit:GausHitFinder 0.552904 1.23317 2.88122 1.14782 0.447124 24
produce:nhitsfilter:NumberOfHitsFilter 0.000204586 0.000335596 0.00054821 0.000295662 0.000114141 24
produce:reco3d:SpacePointSolver 5.28096 14.5101 54.4277 12.4436 9.61183 24
produce:hitpdune:DisambigFromSpacePoints 0.0735747 0.253544 1.13225 0.172733 0.247074 24
produce:pandora:StandardPandora 11.0686 83.003 778.48 23.4633 191.751 24
produce:pandoraTrack:LArPandoraTrackCreation 0.488121 2.04259 13.517 1.11508 2.6999 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0192645 0.03649 0.0820027 0.0330269 0.0122533 24
[art]:TriggerResults:TriggerResultInserter 2.4016e-05 4.69809e-05 7.8146e-05 4.89485e-05 1.2015e-05 24
end_path:out1:RootOutput 5.939e-06 9.20254e-06 2.4056e-05 8.0425e-06 3.93289e-06 24
end_path:out1:RootOutput(write) 3.80093 4.83847 6.94636 5.08711 0.743103 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7091.6 MB
Peak resident set size usage (VmHWM): 5192.44 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040361_0555_df-s04-d1_dw_0_20251105T043610_reco_stage1_20251105T123600_keepup.root
\tHists: np02vd_raw_run040361_0555_df-s04-d1_dw_0_20251105T043610_reco_stage1_20251105T123600_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040361_0555_df-s04-d1_dw_0_20251105T043610_reco_stage1_20251105T123600_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040361_0555_df-s04-d1_dw_0_20251105T043610_reco_stage1_20251105T123600_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": 1762346161.0,
"core.end_time": 1762346161.0,
"core.events": [
51361,
51365,
51369,
51373,
51377,
51381,
51385,
51389,
51393,
51397,
51401,
51405,
51409,
51413,
51417,
51421,
51425,
51429,
51433,
51437,
51441,
51445,
51449,
51453
],
"core.event_count": 24,
"core.first_event_number": 51361,
"core.last_event_number": 51453,
"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_0555_df-s04-d1_dw_0_20251105T043610.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040361_0555_df-s04-d1_dw_0_20251105T043610_reco_stage1_20251105T123600_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": 1762346161.0,
"core.end_time": 1762346161.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_0555_df-s04-d1_dw_0_20251105T043610.hdf5"
}
]
} 