Jobsub ID 240015.121@dunegpschedd01.fnal.gov
| Jobsub ID | 240015.121@dunegpschedd01.fnal.gov |
| Workflow ID | 9375 |
| Stage ID | 1 |
| User name | ykermaid@fnal.gov |
| HTCondor Group | group_dune.prod_mcsim |
| Requested | Processors | 1 |
| GPU | No |
| RSS bytes | 4193255424 (3999 MiB) |
| Wall seconds limit | 18000 (5 hours) |
| Submitted time | 2025-10-29 08:20:14 |
| Site | UK_RAL-Tier1 |
| Entry | LIGO_UK_RAL_arc_ce02 |
| Last heartbeat | 2025-10-29 12:07:20 |
| From worker node | Hostname | dune002-68669.0-lcg2615.gridpp.rl.ac.uk |
| cpuinfo | AMD EPYC 7763 64-Core Processor |
| OS release | Scientific Linux release 7.9 (Nitrogen) |
| Processors | 1 |
| RSS bytes | 4194304000 (4000 MiB) |
| Wall seconds limit | 216000 (60 hours) |
| GPU | |
| Inner Apptainer? | True |
| Job state | aborted |
| Started | 2025-10-29 10:40:28 |
| Input files | vd-protodune:np02vd_raw_run040267_0267_df-s04-d3_dw_0_20251026T020204.hdf5
|
| Outputting started | 2025-10-29 11:48:48 |
| Output files | |
| Finished | 2025-10-29 12:07:20 |
| List job events Cached HTCondor job logs |
Jobscript log (last 10,000 characters)
all=43 anode=0
[11:46:38.352] D [ glue ] <FrameFanin:nfsp> EOS at call=43 with 8
[11:46:38.352] D [ glue ] frame sink sees EOS
[11:46:38.352] D [ pgraph ] <Pgrapher:> graph execution complete
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.07 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.37 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.01 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.48 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.47 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.41 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.04 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.91 sec
[11:46:38.352] I [ timer ] Timer: WireCell::Aux::Resampler : 0.69 sec
[11:46:38.352] I [ timer ] Timer: WireCell::Aux::Resampler : 0.67 sec
[11:46:38.352] I [ timer ] Timer: WireCell::Aux::Resampler : 0.64 sec
[11:46:38.352] I [ timer ] Timer: WireCell::Aux::Resampler : 0.56 sec
[11:46:38.352] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:38.352] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:38.353] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:38.353] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:46:38.353] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:46:38.353] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[11:46:38.353] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:46:38.353] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:46:38.353] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:46:38.353] I [ timer ] Timer: Total node execution : 116.36999927647412 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 54076 traces tagged "gauss"
FrameSaver: q=1.57977e+07 n=1137388 tag=gauss
wclsFrameSaver: saving 68222 traces tagged "wiener"
FrameSaver: q=1.70258e+07 n=1079531 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 4797 XUs and 4157 XVs -> 226 XUVs
C:0 T:1 605 XUs and 895 XVs -> 26 XUVs
C:0 T:2 935 XUs and 1074 XVs -> 27 XUVs
C:0 T:3 665 XUs and 689 XVs -> 42 XUVs
C:0 T:4 254 XUs and 366 XVs -> 5 XUVs
C:0 T:5 2557 XUs and 2702 XVs -> 100 XUVs
C:0 T:6 485 XUs and 754 XVs -> 24 XUVs
C:0 T:7 51565 XUs and 59177 XVs -> 4325 XUVs
C:0 T:8 477 XUs and 584 XVs -> 34 XUVs
C:0 T:9 600 XUs and 665 XVs -> 64 XUVs
C:0 T:10 859 XUs and 944 XVs -> 48 XUVs
C:0 T:11 504 XUs and 587 XVs -> 40 XUVs
C:0 T:12 2062 XUs and 2191 XVs -> 147 XUVs
C:0 T:13 486 XUs and 752 XVs -> 60 XUVs
C:0 T:14 1272 XUs and 811 XVs -> 47 XUVs
C:0 T:15 512 XUs and 300 XVs -> 29 XUVs
5244 XUVs total
2515 collection wire objects
5244 potential space points
Neighbour search...
231506 tests to find 48752 neighbours
Iterating with no regularization...
Begin: 5.48784e+10
0 5.03919e+10
1 4.9508e+10
2 4.95007e+10
Now with regularization...
Begin: 4.89728e+10
0 4.89698e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
29-Oct-2025 11:48:36 UTC Closed output file "np02vd_raw_run040267_0267_df-s04-d3_dw_0_20251026T020204_reco_stage1_20251029T114836_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 108.144 183.12 397.499 153.006 73.4466 22
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.4053e-05 0.000132976 0.000273148 0.000108798 5.4166e-05 22
produce:tpcrawdecoder:PDVDTPCReader 15.5537 18.0194 24.2516 17.3631 2.11761 22
produce:triggerrawdecoder:PDVDTriggerReader4 0.387908 0.402796 0.430568 0.402533 0.0120982 22
produce:pdvddaphne:DAPHNEReaderPDVD 0.000415422 0.000543477 0.00105227 0.000508754 0.000129732 22
produce:ophit:OpHitFinder 7.0322e-05 0.000181729 0.000954326 0.000145064 0.000172969 22
produce:opflash:OpFlashFinderVerticalDrift 5.7151e-05 0.000109966 0.000497684 9.16525e-05 8.63605e-05 22
produce:wclsdatavd:WireCellToolkit 55.2243 89.3229 171.918 78.1989 29.415 22
produce:gaushit:GausHitFinder 1.09618 1.7637 2.62329 1.76882 0.439701 22
produce:nhitsfilter:NumberOfHitsFilter 0.000279228 0.000422603 0.000638288 0.000404776 0.00010504 22
produce:reco3d:SpacePointSolver 8.18193 16.1864 29.4179 15.7157 5.72299 22
produce:hitpdune:DisambigFromSpacePoints 0.135304 0.322649 0.725771 0.280918 0.155475 22
produce:pandora:StandardPandora 15.3387 49.081 206.734 33.388 43.6591 22
produce:pandoraTrack:LArPandoraTrackCreation 0.537041 1.55746 4.74117 1.16052 1.04312 22
produce:pandoraGnocalo:GnocchiCalorimetry 0.0214715 0.0501366 0.0754877 0.0516539 0.01401 22
[art]:TriggerResults:TriggerResultInserter 2.77e-05 3.91242e-05 6.2451e-05 3.7571e-05 7.48199e-06 22
end_path:out1:RootOutput 1.094e-05 1.79283e-05 5.3182e-05 1.36155e-05 9.54076e-06 22
end_path:out1:RootOutput(write) 4.87558 6.35749 8.03917 6.29217 0.700366 22
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5652.15 MB
Peak resident set size usage (VmHWM): 3485.78 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040267_0267_df-s04-d3_dw_0_20251026T020204_reco_stage1_20251029T114836_keepup.root
\tHists: np02vd_raw_run040267_0267_df-s04-d3_dw_0_20251026T020204_reco_stage1_20251029T114836_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040267_0267_df-s04-d3_dw_0_20251026T020204_reco_stage1_20251029T114836_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040267_0267_df-s04-d3_dw_0_20251026T020204_reco_stage1_20251029T114836_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": 1761738516.0,
"core.end_time": 1761738516.0,
"core.events": [
49487,
49495,
49503,
49511,
49519,
49527,
49535,
49543,
49551,
49559,
49567,
49575,
49583,
49591,
49599,
49607,
49615,
49623,
49631,
49639,
49647,
49655
],
"core.event_count": 22,
"core.first_event_number": 49487,
"core.last_event_number": 49655,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40267
],
"core.runs_subruns": [
4026700001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040267_0267_df-s04-d3_dw_0_20251026T020204.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040267_0267_df-s04-d3_dw_0_20251026T020204_reco_stage1_20251029T114836_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": 1761738516.0,
"core.end_time": 1761738516.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40267
],
"core.runs_subruns": [
4026700001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040267_0267_df-s04-d3_dw_0_20251026T020204.hdf5"
}
]
} 