justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 40360.120@dunegpschedd02.fnal.gov

Jobsub ID40360.120@dunegpschedd02.fnal.gov
Workflow ID2641
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-16 07:48:42
SiteCERN
EntryCMSHTPC_T2_CH_CERN_ce511
Last heartbeat2025-09-16 08:41:14
From worker nodeHostnameb9p09p4833.cern.ch
cpuinfoAMD EPYC 7543 32-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit343800 (95 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-09-16 07:50:12
Input filesvd-protodune:np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600.hdf5
JobscriptExit code0
Real time49m (2984s)
CPU time47m (2874s = 96%)
Max RSS bytes3208364032 (3059 MiB)
Outputting started2025-09-16 08:39:58
Output filesvd-protodune-det-reco:np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600_reco_stage1_20250916T083946_keepup.root
Finished2025-09-16 08:41:14
Saved logsjustin-logs:40360.120-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

ion complete 
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.76 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.53 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.07 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.81 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.59 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.54 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.54 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.97 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.17 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.13 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.12 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.67 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[10:38:44.705] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[10:38:44.705] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[10:38:44.705] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[10:38:44.705] I [ timer  ] Timer: Total node execution : 65.9199994392693 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 46323 traces tagged "gauss"
FrameSaver: q=6.85611e+06 n=967033 tag=gauss
wclsFrameSaver: saving 55709 traces tagged "wiener"
FrameSaver: q=7.20361e+06 n=940707 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1455 XUs and 1335 XVs -> 91 XUVs
C:0 T:1 863 XUs and 990 XVs -> 44 XUVs
C:0 T:2 1038 XUs and 906 XVs -> 44 XUVs
C:0 T:3 52 XUs and 40 XVs -> 1 XUVs
C:0 T:4 792 XUs and 809 XVs -> 34 XUVs
C:0 T:5 1095 XUs and 1450 XVs -> 83 XUVs
C:0 T:6 1626 XUs and 2082 XVs -> 69 XUVs
C:0 T:7 439 XUs and 298 XVs -> 11 XUVs
C:0 T:8 1666 XUs and 1559 XVs -> 103 XUVs
C:0 T:9 430 XUs and 415 XVs -> 55 XUVs
C:0 T:10 1349 XUs and 1405 XVs -> 71 XUVs
C:0 T:11 1563 XUs and 1476 XVs -> 124 XUVs
C:0 T:12 768 XUs and 697 XVs -> 56 XUVs
C:0 T:13 1374 XUs and 1821 XVs -> 79 XUVs
C:0 T:14 1267 XUs and 1445 XVs -> 80 XUVs
C:0 T:15 3431 XUs and 3080 XVs -> 142 XUVs
1087 XUVs total
945 collection wire objects
1087 potential space points
Neighbour search...
6483 tests to find 3572 neighbours
Iterating with no regularization...
Begin: 3.02341e+08
0 2.95826e+08
1 2.95593e+08
Now with regularization...
Begin: 2.89871e+08
0 2.89759e+08
PcaShowerParticleBuildingAlgorithm::OpeningAngle - principal eigenvalue less than or equal to 0.
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
16-Sep-2025 10:39:46 CEST  Closed output file "np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600_reco_stage1_20250916T083946_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        99.1631       154.601       381.636       132.232       63.4051        19     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                       5.626e-05    0.000107118   0.000304074   8.3251e-05    5.54357e-05      19     
produce:tpcrawdecoder:PDVDTPCReader               6.98618       7.26686       7.74721       7.17124      0.234535        19     
produce:triggerrawdecoder:PDVDTriggerReader4    0.00649015     0.0111398     0.0448529    0.00736494     0.0106586       19     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000216792   0.000347595   0.000713888   0.000320544   0.000101873      19     
produce:ophit:OpHitFinder                       0.000134382   0.000250728   0.00093937    0.000200582   0.000180227      19     
produce:opflash:OpFlashFinderVerticalDrift       4.682e-05    7.56592e-05   0.000368223   6.0061e-05    6.95238e-05      19     
produce:wclsdatavd:WireCellToolkit                54.1129       67.8611       84.4644       66.6269       7.92629        19     
produce:gaushit:GausHitFinder                    0.645695       1.32267       2.27207       1.28217      0.451188        19     
produce:nhitsfilter:NumberOfHitsFilter          0.000189532   0.000311915   0.000679757   0.000274823   0.000110666      19     
produce:reco3d:SpacePointSolver                   7.73194       14.0929       29.1519       12.3372       5.39554        19     
produce:hitpdune:DisambigFromSpacePoints         0.0845109     0.207624      0.393922      0.184871      0.094122        19     
produce:pandora:StandardPandora                   14.5639       57.8059       255.365       33.4873       53.6045        19     
produce:pandoraTrack:LArPandoraTrackCreation     0.322686       0.8931        1.85268      0.830544      0.338544        19     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0184588     0.0287281     0.0534382     0.0256603    0.00886161       19     
[art]:TriggerResults:TriggerResultInserter       1.982e-05    2.94687e-05   7.8771e-05     2.709e-05    1.24879e-05      19     
end_path:out1:RootOutput                         3.83e-06     9.25174e-06   3.1721e-05      6.9e-06     7.79442e-06      19     
end_path:out1:RootOutput(write)                   3.91804       5.08261       6.26289       4.65551      0.959233        19     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5148.82 MB
  Peak resident set size usage (VmHWM): 3208.36 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600_reco_stage1_20250916T083946_keepup.root
\tHists: np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600_reco_stage1_20250916T083947_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600_reco_stage1_20250916T083946_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600_reco_stage1_20250916T083946_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": 1758011987.0,
    "core.end_time": 1758011987.0,
    "core.events": [
      57637,
      57641,
      57645,
      57649,
      57653,
      57657,
      57661,
      57665,
      57669,
      57673,
      57677,
      57681,
      57685,
      57689,
      57693,
      57697,
      57701,
      57705,
      57709
    ],
    "core.event_count": 19,
    "core.first_event_number": 57637,
    "core.last_event_number": 57709,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39353
    ],
    "core.runs_subruns": [
      3935300001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600_reco_stage1_20250916T083947_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": 1758011987.0,
    "core.end_time": 1758011987.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39353
    ],
    "core.runs_subruns": [
      3935300001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039353_0749_df-s05-d1_dw_0_20250916T064600.hdf5"
    }
  ]
}
justIN time: 2025-09-19 05:18:09 UTC       justIN version: 01.05.00