Jobsub ID 42563.51@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
sSigProc:anode1sigproc1> EOS at call=79 anode=1
[20:47:22.367] D [ glue ] <ChannelSelector:chsel0> see EOS at call=39
[20:47:22.367] D [ aux ] <Resampler:resmp0> EOS at call=79
[20:47:22.367] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[20:47:22.367] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[20:47:22.367] D [ glue ] frame sink sees EOS
[20:47:22.367] D [ pgraph ] <Pgrapher:> graph execution complete
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.23 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.28 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.94 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.04 sec
[20:47:22.367] I [ timer ] Timer: WireCell::Aux::Resampler : 0.44 sec
[20:47:22.367] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[20:47:22.367] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[20:47:22.367] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[20:47:22.367] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:22.367] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[20:47:22.367] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[20:47:22.367] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[20:47:22.367] I [ timer ] Timer: Total node execution : 41.089998830109835 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 18025 traces tagged "gauss"
FrameSaver: q=1.16835e+07 n=544351 tag=gauss
wclsFrameSaver: saving 23391 traces tagged "wiener"
FrameSaver: q=1.23957e+07 n=528706 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 89 XUs and 128 XVs -> 3 XUVs
C:0 T:1 571 XUs and 890 XVs -> 22 XUVs
C:0 T:2 86 XUs and 113 XVs -> 2 XUVs
C:0 T:3 16 XUs and 22 XVs -> 0 XUVs
C:0 T:4 429 XUs and 641 XVs -> 29 XUVs
C:0 T:5 42426 XUs and 61160 XVs -> 4374 XUVs
C:0 T:6 89 XUs and 75 XVs -> 4 XUVs
C:0 T:7 202 XUs and 249 XVs -> 16 XUVs
4450 XUVs total
1765 collection wire objects
4450 potential space points
Neighbour search...
196216 tests to find 40778 neighbours
Iterating with no regularization...
Begin: 1.04349e+11
0 1.0116e+11
1 1.01008e+11
2 1.01001e+11
Now with regularization...
Begin: 9.97508e+10
0 9.97487e+10
18-Sep-2025 20:48:51 UTC Closed output file "np02vd_raw_run039499_0026_df-s04-d3_dw_0_20250918T183356_reco_stage1_20250918T204851_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 40.4716 69.8604 211.679 55.0274 38.6873 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.7982e-05 0.000127247 0.000574127 0.000101378 8.21386e-05 40
produce:tpcrawdecoder:PDVDTPCReader 6.42345 7.3442 9.50134 6.82717 0.967034 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.387748 0.390622 0.393565 0.390295 0.00146187 40
produce:pdvddaphne:DAPHNEReaderPDVD 2.18373 2.66209 3.04436 2.65666 0.21218 40
produce:ophit:OpHitFinder 0.0240506 0.0364579 0.0533778 0.0360207 0.00531382 40
produce:opflash:OpFlashFinderVerticalDrift 0.00317662 0.00860944 0.0143073 0.00815926 0.00253564 40
produce:wclsdatavd:WireCellToolkit 20.4884 26.5863 42.7401 23.7497 6.10282 40
produce:gaushit:GausHitFinder 0.211704 0.491695 0.843587 0.454207 0.147644 40
produce:nhitsfilter:NumberOfHitsFilter 8.4738e-05 0.000180267 0.000483581 0.000150883 9.66104e-05 40
produce:reco3d:SpacePointSolver 1.59602 4.83953 13.5836 4.14346 2.31202 40
produce:hitpdune:DisambigFromSpacePoints 0.0118825 0.0710488 0.285586 0.0509646 0.0550393 40
produce:pandora:StandardPandora 2.665 25.058 147.923 12.4727 33.0398 40
produce:pandoraTrack:LArPandoraTrackCreation 0.0702758 0.289914 0.997913 0.224297 0.202207 40
produce:pandoraGnocalo:GnocchiCalorimetry 0.0059847 0.0134285 0.0293274 0.0125994 0.00489068 40
[art]:TriggerResults:TriggerResultInserter 1.365e-05 3.03806e-05 7.0697e-05 2.65655e-05 1.43449e-05 40
end_path:out1:RootOutput 2.804e-06 6.42308e-06 2.0581e-05 6.2545e-06 3.33768e-06 40
end_path:out1:RootOutput(write) 1.56559 2.04562 2.44139 2.07459 0.220181 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4977.04 MB
Peak resident set size usage (VmHWM): 2684.97 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0026_df-s04-d3_dw_0_20250918T183356_reco_stage1_20250918T204851_keepup.root
\tHists: np02vd_raw_run039499_0026_df-s04-d3_dw_0_20250918T183356_reco_stage1_20250918T204851_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0026_df-s04-d3_dw_0_20250918T183356_reco_stage1_20250918T204851_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0026_df-s04-d3_dw_0_20250918T183356_reco_stage1_20250918T204851_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_10_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": 1758228531.0,
"core.end_time": 1758228531.0,
"core.events": [
6344,
6350,
6356,
6362,
6368,
6374,
6380,
6386,
6392,
6398,
6404,
6410,
6416,
6422,
6428,
6434,
6440,
6446,
6452,
6458,
6464,
6470,
6476,
6482,
6488,
6494,
6500,
6506,
6512,
6518,
6524,
6530,
6536,
6542,
6548,
6554,
6560,
6566,
6572,
6578
],
"core.event_count": 40,
"core.first_event_number": 6344,
"core.last_event_number": 6578,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0026_df-s04-d3_dw_0_20250918T183356.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0026_df-s04-d3_dw_0_20250918T183356_reco_stage1_20250918T204851_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_10_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": 1758228531.0,
"core.end_time": 1758228531.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0026_df-s04-d3_dw_0_20250918T183356.hdf5"
}
]
} 