justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 43070.82@dunegpschedd01.fnal.gov

Jobsub ID43070.82@dunegpschedd01.fnal.gov
Workflow ID2323
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 11:37:51
SiteCZ_FZU
EntryNova_CZ_FZU_osgce1
Last heartbeat2025-09-08 15:53:10
From worker nodeHostnamemilan17.farm.particle.cz
cpuinfoAMD EPYC 7543 32-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit172800 (48 hours)
GPU
Inner Apptainer?True
Job stateoutputting_failed
Started2025-09-08 14:28:31
Input filesvd-protodune:np02vd_raw_run039324_1707_df-s05-d3_dw_0_20250907T041231.hdf5
JobscriptExit code0
Real time1h (4869s)
CPU time1h (4124s = 84%)
Max RSS bytes2940219392 (2804 MiB)
Outputting started2025-09-08 15:49:41
Output files
Finished2025-09-08 15:53:10
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

.326] D [  glue  ] <FrameFanin:nfsp> EOS at call=53 with 8 
[17:42:55.326] D [  glue  ] frame sink sees EOS
[17:42:55.326] D [ pgraph ] <Pgrapher:> graph execution complete 
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.41 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.69 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.46 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.42 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.91 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.88 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.6 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.41 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.41 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.41 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.41 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.37 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:55.326] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:55.327] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:42:55.327] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:55.327] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:55.327] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:42:55.327] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[17:42:55.327] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[17:42:55.327] I [ timer  ] Timer: Total node execution : 55.41999972611666 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 42416 traces tagged "gauss"
FrameSaver: q=7.5711e+06 n=966630 tag=gauss
wclsFrameSaver: saving 52844 traces tagged "wiener"
FrameSaver: q=8.12278e+06 n=931322 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1982 XUs and 1679 XVs -> 65 XUVs
C:0 T:1 2539 XUs and 2767 XVs -> 73 XUVs
C:0 T:2 705 XUs and 417 XVs -> 24 XUVs
C:0 T:3 210 XUs and 310 XVs -> 5 XUVs
C:0 T:4 643 XUs and 663 XVs -> 31 XUVs
C:0 T:5 75 XUs and 88 XVs -> 3 XUVs
C:0 T:6 187 XUs and 173 XVs -> 12 XUVs
C:0 T:7 884 XUs and 884 XVs -> 55 XUVs
C:0 T:8 23029 XUs and 33281 XVs -> 6312 XUVs
C:0 T:9 14604 XUs and 27844 XVs -> 4298 XUVs
C:0 T:10 10739 XUs and 14095 XVs -> 2365 XUVs
C:0 T:11 433 XUs and 590 XVs -> 34 XUVs
C:0 T:12 2476 XUs and 3216 XVs -> 544 XUVs
C:0 T:13 499 XUs and 443 XVs -> 34 XUVs
C:0 T:14 978 XUs and 1276 XVs -> 52 XUVs
C:0 T:15 540 XUs and 578 XVs -> 27 XUVs
13934 XUVs total
1731 collection wire objects
13934 potential space points
Neighbour search...
2310742 tests to find 974876 neighbours
Iterating with no regularization...
Begin: 3.44601e+08
0 2.94896e+08
1 2.91526e+08
2 2.90906e+08
3 2.90683e+08
Now with regularization...
Begin: 2.8228e+08
0 2.82049e+08
08-Sep-2025 17:45:18 CEST  Closed output file "np02vd_raw_run039324_1707_df-s05-d3_dw_0_20250907T041231_reco_stage1_20250908T154518_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        101.067       168.479       269.888       160.262       45.1793        27     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                       8.753e-05    0.000142632   0.000276649   0.00013085    4.84636e-05      27     
produce:tpcrawdecoder:PDVDTPCReader               11.7007       15.3811       51.8077       13.1764       7.92047        27     
produce:triggerrawdecoder:PDVDTriggerReader4     0.0316886     0.0325518     0.0336082     0.0324685    0.000551061      27     
produce:pdvddaphne:DAPHNEReaderPDVD               3.0102        3.73477       4.21542       3.75548      0.360279        27     
produce:ophit:OpHitFinder                        0.0426731     0.0636653     0.105182      0.0611462     0.0135956       27     
produce:opflash:OpFlashFinderVerticalDrift       0.0092311     0.0165039     0.0299542     0.0157428    0.00378895       27     
produce:wclsdatavd:WireCellToolkit                48.5534       56.9346       93.9419       55.1734       9.97573        27     
produce:gaushit:GausHitFinder                    0.836878       1.5001        2.34163       1.50788      0.379775        27     
produce:nhitsfilter:NumberOfHitsFilter          0.000233179   0.000573546   0.00138101    0.000417888   0.000343664      27     
produce:reco3d:SpacePointSolver                   7.64633       13.8995       25.1608       14.2455       4.47554        27     
produce:hitpdune:DisambigFromSpacePoints         0.111215      0.253718      0.469937      0.249462      0.0960542       27     
produce:pandora:StandardPandora                   23.1151       70.348        153.426       61.7991       34.3312        27     
produce:pandoraTrack:LArPandoraTrackCreation     0.218332       0.88528       2.44441      0.857316      0.472675        27     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0194757     0.0360033     0.100537      0.0327284     0.0153349       27     
[art]:TriggerResults:TriggerResultInserter       2.66e-05     3.93123e-05   9.2819e-05    3.7919e-05    1.26145e-05      27     
end_path:out1:RootOutput                         5.27e-06      1.096e-05     4.003e-05     8.68e-06     7.11145e-06      27     
end_path:out1:RootOutput(write)                   4.60524       5.33429       8.21038       5.15669      0.804229        27     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4866.81 MB
  Peak resident set size usage (VmHWM): 2940.22 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039324_1707_df-s05-d3_dw_0_20250907T041231_reco_stage1_20250908T154518_keepup.root
\tHists: np02vd_raw_run039324_1707_df-s05-d3_dw_0_20250907T041231_reco_stage1_20250908T154519_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039324_1707_df-s05-d3_dw_0_20250907T041231_reco_stage1_20250908T154518_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039324_1707_df-s05-d3_dw_0_20250907T041231_reco_stage1_20250908T154518_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": 1757346319.0,
    "core.end_time": 1757346319.0,
    "core.events": [
      893917,
      893937,
      893957,
      893977,
      893997,
      894017,
      894037,
      894057,
      894077,
      894097,
      894117,
      894137,
      894157,
      894177,
      894197,
      894217,
      894237,
      894257,
      894277,
      894297,
      894317,
      894337,
      894357,
      894377,
      894397,
      894417,
      894437
    ],
    "core.event_count": 27,
    "core.first_event_number": 893917,
    "core.last_event_number": 894437,
    "core.data_stream": "physics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39324
    ],
    "core.runs_subruns": [
      3932400001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039324_1707_df-s05-d3_dw_0_20250907T041231.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039324_1707_df-s05-d3_dw_0_20250907T041231_reco_stage1_20250908T154519_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": 1757346319.0,
    "core.end_time": 1757346319.0,
    "core.data_stream": "physics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39324
    ],
    "core.runs_subruns": [
      3932400001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039324_1707_df-s05-d3_dw_0_20250907T041231.hdf5"
    }
  ]
}
justIN time: 2025-09-19 08:54:30 UTC       justIN version: 01.05.00