justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 36362.182@dunegpschedd02.fnal.gov

Jobsub ID36362.182@dunegpschedd02.fnal.gov
Workflow ID2330
Stage ID1
User nameykermaid@fnal.gov
HTCondor Groupgroup_dune.prod.mcsim
RequestedProcessors1
GPUNo
RSS bytes4193255424 (3999 MiB)
Wall seconds limit18000 (5 hours)
Submitted time2025-09-08 12:47:59
SiteNL_NIKHEF
EntryVIRGO_NL_NIKHEF_juk
Last heartbeat2025-09-08 15:52:25
From worker nodeHostnamewn-lot-033.farm.nikhef.nl
cpuinfoAMD EPYC 7702P 64-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit129600 (36 hours)
GPU
Inner Apptainer?True
Job stateoutputting_failed
Started2025-09-08 14:44:23
Input filesvd-protodune:np02vd_raw_run039327_0015_df-s04-d1_dw_0_20250908T104847.hdf5
JobscriptExit code0
Real time1h (3972s)
CPU time48m (2917s = 73%)
Max RSS bytes2764804096 (2636 MiB)
Outputting started2025-09-08 15:50:36
Output files
Finished2025-09-08 15:52:25
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

] <ChannelSelector:chsel0> see EOS at call=18 
[17:45:24.486] D [  aux   ] <Resampler:resmp0> EOS at call=37 
[17:45:24.486] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=37 anode=0 
[17:45:24.486] D [  glue  ] <FrameFanin:nfsp> EOS at call=37 with 8 
[17:45:24.486] D [  glue  ] frame sink sees EOS
[17:45:24.487] D [ pgraph ] <Pgrapher:> graph execution complete 
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.93 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.03 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.98 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.45 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.43 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.33 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.29 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.47 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.93 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.93 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.91 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.9 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[17:45:24.487] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:45:24.487] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[17:45:24.487] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[17:45:24.487] I [ timer  ] Timer: Total node execution : 64.61999938637018 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 40049 traces tagged "gauss"
FrameSaver: q=6.04584e+06 n=774265 tag=gauss
wclsFrameSaver: saving 47696 traces tagged "wiener"
FrameSaver: q=6.34081e+06 n=744198 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 264 XUs and 191 XVs -> 6 XUVs
C:0 T:1 749 XUs and 564 XVs -> 42 XUVs
C:0 T:2 430 XUs and 362 XVs -> 16 XUVs
C:0 T:3 365 XUs and 459 XVs -> 16 XUVs
C:0 T:4 737 XUs and 847 XVs -> 51 XUVs
C:0 T:5 507 XUs and 516 XVs -> 21 XUVs
C:0 T:6 60 XUs and 83 XVs -> 1 XUVs
C:0 T:7 46 XUs and 94 XVs -> 4 XUVs
C:0 T:8 679 XUs and 1066 XVs -> 67 XUVs
C:0 T:9 513 XUs and 882 XVs -> 52 XUVs
C:0 T:10 1894 XUs and 1871 XVs -> 127 XUVs
C:0 T:11 1914 XUs and 2493 XVs -> 154 XUVs
C:0 T:12 1126 XUs and 2026 XVs -> 93 XUVs
C:0 T:13 1285 XUs and 1874 XVs -> 80 XUVs
C:0 T:14 1655 XUs and 2840 XVs -> 119 XUVs
C:0 T:15 1238 XUs and 602 XVs -> 41 XUVs
890 XUVs total
773 collection wire objects
890 potential space points
Neighbour search...
5218 tests to find 2644 neighbours
Iterating with no regularization...
Begin: 2.86652e+08
0 2.82007e+08
1 2.81994e+08
Now with regularization...
Begin: 2.78561e+08
0 2.7856e+08
08-Sep-2025 17:46:14 CEST  Closed output file "np02vd_raw_run039327_0015_df-s04-d1_dw_0_20250908T104847_reco_stage1_20250908T154614_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        139.523       193.151       260.878       189.127       29.9598        19     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      6.1295e-05    7.84602e-05   0.000183104   7.4239e-05    2.5592e-05       19     
produce:tpcrawdecoder:PDVDTPCReader               20.6131       44.6598       58.0159       47.152        10.1244        19     
produce:triggerrawdecoder:PDVDTriggerReader4     0.513685      0.549186      0.619104      0.548841      0.0243967       19     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000252904   0.000290347   0.000586359   0.000277079   7.07585e-05      19     
produce:ophit:OpHitFinder                       9.9066e-05    0.00013595    0.000624801   0.000104726   0.000116665      19     
produce:opflash:OpFlashFinderVerticalDrift       3.73e-05     5.17369e-05   0.000273763   3.9083e-05    5.23742e-05      19     
produce:wclsdatavd:WireCellToolkit                63.7805       71.6744       87.2692       69.3013       5.46767        19     
produce:gaushit:GausHitFinder                    0.951596       1.25521       1.94277       1.25501       0.22526        19     
produce:nhitsfilter:NumberOfHitsFilter          0.000283101   0.000350201   0.000489688   0.000342282   5.62385e-05      19     
produce:reco3d:SpacePointSolver                   10.8759       15.3145       24.5994       15.0284       3.45266        19     
produce:hitpdune:DisambigFromSpacePoints         0.130126      0.212377      0.437917      0.203637      0.0667767       19     
produce:pandora:StandardPandora                   26.3003       53.1262       94.4581       50.6667       18.8182        19     
produce:pandoraTrack:LArPandoraTrackCreation     0.576946       1.08856       2.31528      0.951628      0.403721        19     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0252741     0.032504      0.0453238     0.0324697    0.00539271       19     
[art]:TriggerResults:TriggerResultInserter      1.6561e-05    2.15947e-05   5.5604e-05    1.9777e-05    8.39544e-06      19     
end_path:out1:RootOutput                         3.496e-06    4.75942e-06   1.8805e-05     3.968e-06    3.3225e-06       19     
end_path:out1:RootOutput(write)                   4.92516       5.19907       5.48082       5.19773      0.159653        19     
==================================================================================================================================

====================================================================================================
MemoryTracker summary (base-10 MB units used)

  Peak virtual memory usage (VmPeak)  : 4646.4 MB
  Peak resident set size usage (VmHWM): 2764.8 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039327_0015_df-s04-d1_dw_0_20250908T104847_reco_stage1_20250908T154614_keepup.root
\tHists: np02vd_raw_run039327_0015_df-s04-d1_dw_0_20250908T104847_reco_stage1_20250908T154615_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039327_0015_df-s04-d1_dw_0_20250908T104847_reco_stage1_20250908T154614_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039327_0015_df-s04-d1_dw_0_20250908T104847_reco_stage1_20250908T154614_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": 1757346375.0,
    "core.end_time": 1757346375.0,
    "core.events": [
      1169,
      1173,
      1177,
      1181,
      1185,
      1189,
      1193,
      1197,
      1201,
      1205,
      1209,
      1213,
      1217,
      1221,
      1225,
      1229,
      1233,
      1237,
      1241
    ],
    "core.event_count": 19,
    "core.first_event_number": 1169,
    "core.last_event_number": 1241,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39327
    ],
    "core.runs_subruns": [
      3932700001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039327_0015_df-s04-d1_dw_0_20250908T104847.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039327_0015_df-s04-d1_dw_0_20250908T104847_reco_stage1_20250908T154615_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": 1757346375.0,
    "core.end_time": 1757346375.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39327
    ],
    "core.runs_subruns": [
      3932700001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039327_0015_df-s04-d1_dw_0_20250908T104847.hdf5"
    }
  ]
}
justIN time: 2025-09-19 12:47:01 UTC       justIN version: 01.05.00