justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 49096.166@dunegpschedd01.fnal.gov

Jobsub ID49096.166@dunegpschedd01.fnal.gov
Workflow ID2920
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-18 21:10:53
SiteCZ_FZU
EntryNova_CZ_FZU_osgce1
Last heartbeat2025-09-19 01:59:40
From worker nodeHostnamemilan25.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 statefinished
Started2025-09-19 00:35:14
Input filesvd-protodune:np02vd_raw_run039499_0127_df-s04-d1_dw_0_20250918T215732.hdf5
JobscriptExit code0
Real time1h (4957s)
CPU time1h (4489s = 90%)
Max RSS bytes2750963712 (2623 MiB)
Outputting started2025-09-19 01:57:52
Output filesvd-protodune-det-reco:np02vd_raw_run039499_0127_df-s04-d1_dw_0_20250918T215732_reco_stage1_20250919T015740_keepup.root
Finished2025-09-19 01:59:40
Saved logsjustin-logs:49096.166-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

] <Resampler:resmp0> EOS at call=81 
[03:57:05.462] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0 
[03:57:05.462] D [  glue  ] <FrameFanin:nfsp> EOS at call=81 with 8 
[03:57:05.462] D [  glue  ] frame sink sees EOS
[03:57:05.462] D [ pgraph ] <Pgrapher:> graph execution complete 
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.78 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.64 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.28 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.23 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.53 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.53 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.51 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.51 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[03:57:05.462] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[03:57:05.462] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:57:05.462] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[03:57:05.462] I [ timer  ] Timer: Total node execution : 48.019999274984 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 21284 traces tagged "gauss"
FrameSaver: q=4.04652e+06 n=528533 tag=gauss
wclsFrameSaver: saving 26740 traces tagged "wiener"
FrameSaver: q=4.31368e+06 n=510541 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1898 XUs and 2427 XVs -> 157 XUVs
C:0 T:1 273 XUs and 532 XVs -> 27 XUVs
C:0 T:2 476 XUs and 443 XVs -> 17 XUVs
C:0 T:3 519 XUs and 578 XVs -> 16 XUVs
C:0 T:4 7322 XUs and 12811 XVs -> 740 XUVs
C:0 T:5 577 XUs and 693 XVs -> 20 XUVs
C:0 T:6 1381 XUs and 2340 XVs -> 76 XUVs
C:0 T:7 3442 XUs and 5795 XVs -> 308 XUVs
1361 XUVs total
857 collection wire objects
1361 potential space points
Neighbour search...
32663 tests to find 9104 neighbours
Iterating with no regularization...
Begin: 3.89176e+09
0 3.70385e+09
1 3.69827e+09
2 3.69824e+09
Now with regularization...
Begin: 3.66029e+09
0 3.66021e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 03:57:40 CEST  Closed output file "np02vd_raw_run039499_0127_df-s04-d1_dw_0_20250918T215732_reco_stage1_20250919T015740_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        47.9981       119.067       642.623       79.4027       122.997        41     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                       6.42e-05     0.000111098   0.00022288     9.681e-05    3.92034e-05      41     
produce:tpcrawdecoder:PDVDTPCReader               5.66376       6.84479       8.55187       6.65864      0.937606        41     
produce:triggerrawdecoder:PDVDTriggerReader4     0.265552      0.271289      0.302744      0.269087     0.00657949       41     
produce:pdvddaphne:DAPHNEReaderPDVD               2.96604       3.39394       4.31129       3.3259       0.291569        41     
produce:ophit:OpHitFinder                        0.0429277     0.0540142     0.0804353     0.0541902    0.00713043       41     
produce:opflash:OpFlashFinderVerticalDrift      0.00479423     0.0118501     0.021464      0.011879     0.00318701       41     
produce:wclsdatavd:WireCellToolkit                25.3085       39.0347       75.8172       32.7719       10.6531        41     
produce:gaushit:GausHitFinder                    0.375332      0.802855       1.71682      0.736292       0.27291        41     
produce:nhitsfilter:NumberOfHitsFilter          0.00010913    0.000183903   0.000371321   0.000170841   5.41494e-05      41     
produce:reco3d:SpacePointSolver                   2.78945       7.55869       26.3101       6.05947       5.2606         41     
produce:hitpdune:DisambigFromSpacePoints         0.0303262     0.124768      0.592042      0.0889051     0.115494        41     
produce:pandora:StandardPandora                   6.03055       57.3246        564.4        20.8812       114.097        41     
produce:pandoraTrack:LArPandoraTrackCreation      0.14961      0.487417       2.1639       0.321185      0.460777        41     
produce:pandoraGnocalo:GnocchiCalorimetry        0.011554      0.0201969     0.0418105     0.0169115    0.00748924       41     
[art]:TriggerResults:TriggerResultInserter       1.665e-05    2.97025e-05   8.2131e-05     2.612e-05    1.29274e-05      41     
end_path:out1:RootOutput                         4.11e-06     8.77756e-06    3.219e-05     8.14e-06     4.01871e-06      41     
end_path:out1:RootOutput(write)                   2.72588       3.11976       3.81778       3.02725      0.288656        41     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4643.43 MB
  Peak resident set size usage (VmHWM): 2750.96 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0127_df-s04-d1_dw_0_20250918T215732_reco_stage1_20250919T015740_keepup.root
\tHists: np02vd_raw_run039499_0127_df-s04-d1_dw_0_20250918T215732_reco_stage1_20250919T015740_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0127_df-s04-d1_dw_0_20250918T215732_reco_stage1_20250919T015740_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039499_0127_df-s04-d1_dw_0_20250918T215732_reco_stage1_20250919T015740_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": 1758247061.0,
    "core.end_time": 1758247061.0,
    "core.events": [
      30954,
      30960,
      30966,
      30972,
      30978,
      30984,
      30990,
      30996,
      31002,
      31008,
      31014,
      31020,
      31026,
      31032,
      31038,
      31044,
      31050,
      31056,
      31062,
      31068,
      31074,
      31080,
      31086,
      31092,
      31098,
      31104,
      31110,
      31116,
      31122,
      31128,
      31134,
      31140,
      31146,
      31152,
      31158,
      31164,
      31170,
      31176,
      31182,
      31188,
      31194
    ],
    "core.event_count": 41,
    "core.first_event_number": 30954,
    "core.last_event_number": 31194,
    "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_0127_df-s04-d1_dw_0_20250918T215732.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039499_0127_df-s04-d1_dw_0_20250918T215732_reco_stage1_20250919T015740_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": 1758247061.0,
    "core.end_time": 1758247061.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_0127_df-s04-d1_dw_0_20250918T215732.hdf5"
    }
  ]
}
justIN time: 2025-09-19 08:48:30 UTC       justIN version: 01.05.00