Jobsub ID 242843.22@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
146] D [ glue ] frame sink sees EOS
[10:30:58.147] D [ pgraph ] <Pgrapher:> graph execution complete
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.9 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.2 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.13 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.83 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.49 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.44 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.35 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.93 sec
[10:30:58.147] I [ timer ] Timer: WireCell::Aux::Resampler : 0.17 sec
[10:30:58.147] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[10:30:58.147] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[10:30:58.147] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[10:30:58.147] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:30:58.147] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:30:58.147] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:30:58.147] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[10:30:58.147] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[10:30:58.147] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[10:30:58.147] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[10:30:58.147] I [ timer ] Timer: Total node execution : 47.939999494701624 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 50733 traces tagged "gauss"
FrameSaver: q=1.3441e+07 n=1142391 tag=gauss
wclsFrameSaver: saving 63736 traces tagged "wiener"
FrameSaver: q=1.44794e+07 n=1091107 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 930 XUs and 1519 XVs -> 48 XUVs
C:0 T:1 2551 XUs and 2962 XVs -> 151 XUVs
C:0 T:2 11330 XUs and 17111 XVs -> 822 XUVs
C:0 T:3 1476 XUs and 2091 XVs -> 55 XUVs
C:0 T:4 323 XUs and 514 XVs -> 16 XUVs
C:0 T:5 647 XUs and 617 XVs -> 34 XUVs
C:0 T:6 484 XUs and 725 XVs -> 17 XUVs
C:0 T:7 964 XUs and 884 XVs -> 43 XUVs
C:0 T:8 345 XUs and 432 XVs -> 19 XUVs
C:0 T:9 1720 XUs and 1976 XVs -> 64 XUVs
C:0 T:10 458 XUs and 527 XVs -> 25 XUVs
C:0 T:11 4649 XUs and 6367 XVs -> 1312 XUVs
C:0 T:12 699 XUs and 853 XVs -> 50 XUVs
C:0 T:13 6495 XUs and 11480 XVs -> 1781 XUVs
C:0 T:14 7369 XUs and 14385 XVs -> 1418 XUVs
C:0 T:15 13177 XUs and 22901 XVs -> 3308 XUVs
9163 XUVs total
1914 collection wire objects
9163 potential space points
Neighbour search...
1029913 tests to find 478898 neighbours
Iterating with no regularization...
Begin: 8.49443e+09
0 7.98906e+09
1 7.97574e+09
2 7.97508e+09
Now with regularization...
Begin: 7.89345e+09
0 7.89309e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
04-Nov-2025 10:31:46 CET Closed output file "np02vd_raw_run040348_0812_df-s04-d0_dw_0_20251104T062254_reco_stage1_20251104T093146_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 67.9859 103.409 240.379 93.1585 34.4163 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.103e-05 8.62116e-05 0.000183682 7.807e-05 2.73894e-05 23
produce:tpcrawdecoder:PDVDTPCReader 4.91414 5.91495 12.2214 5.50693 1.41525 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.0134513 0.0380228 0.365056 0.0199455 0.0702771 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000251993 0.000358165 0.000720818 0.000341324 8.8637e-05 23
produce:ophit:OpHitFinder 5.469e-05 0.000105368 0.000653247 7.8881e-05 0.000117989 23
produce:opflash:OpFlashFinderVerticalDrift 3.814e-05 7.33289e-05 0.000346634 6.1501e-05 5.93212e-05 23
produce:wclsdatavd:WireCellToolkit 43.3622 54.826 92.0077 49.1605 12.313 23
produce:gaushit:GausHitFinder 0.52759 1.07554 1.59229 1.07025 0.282737 23
produce:nhitsfilter:NumberOfHitsFilter 0.000141761 0.000243032 0.000387904 0.000242453 5.36843e-05 23
produce:reco3d:SpacePointSolver 4.63483 10.815 18.0377 11.1001 3.25064 23
produce:hitpdune:DisambigFromSpacePoints 0.0529035 0.170055 0.316462 0.162584 0.0713324 23
produce:pandora:StandardPandora 8.55525 25.5859 113.551 19.4696 20.3267 23
produce:pandoraTrack:LArPandoraTrackCreation 0.169161 0.744112 1.33203 0.788067 0.237209 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0133948 0.0280127 0.0447347 0.0251619 0.00950281 23
[art]:TriggerResults:TriggerResultInserter 1.532e-05 2.49647e-05 8.471e-05 2.258e-05 1.35768e-05 23
end_path:out1:RootOutput 3.13e-06 8.22743e-06 3.387e-05 7.561e-06 5.66189e-06 23
end_path:out1:RootOutput(write) 3.31392 4.17702 5.4897 4.10307 0.583528 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6369.87 MB
Peak resident set size usage (VmHWM): 4416.64 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_0812_df-s04-d0_dw_0_20251104T062254_reco_stage1_20251104T093146_keepup.root
\tHists: np02vd_raw_run040348_0812_df-s04-d0_dw_0_20251104T062254_reco_stage1_20251104T093146_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_0812_df-s04-d0_dw_0_20251104T062254_reco_stage1_20251104T093146_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_0812_df-s04-d0_dw_0_20251104T062254_reco_stage1_20251104T093146_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": 1762248706.0,
"core.end_time": 1762248706.0,
"core.events": [
75016,
75020,
75024,
75028,
75032,
75036,
75040,
75044,
75048,
75052,
75056,
75060,
75064,
75068,
75072,
75076,
75080,
75084,
75088,
75092,
75096,
75100,
75104
],
"core.event_count": 23,
"core.first_event_number": 75016,
"core.last_event_number": 75104,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_0812_df-s04-d0_dw_0_20251104T062254.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_0812_df-s04-d0_dw_0_20251104T062254_reco_stage1_20251104T093146_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": 1762248706.0,
"core.end_time": 1762248706.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_0812_df-s04-d0_dw_0_20251104T062254.hdf5"
}
]
} 