justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 269119.87@dunegpschedd01.fnal.gov

Jobsub ID269119.87@dunegpschedd01.fnal.gov
Workflow ID11264
Stage ID1
User nameavizcaya@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
GPUNo
RSS bytes9437184000 (9000 MiB)
Wall seconds limit80000 (22 hours)
Submitted time2025-12-18 19:29:50
SiteUS_UChicago
EntryEngage_US_MWT2_uiuc_condce_mcore
Last heartbeat2025-12-18 22:21:29
From worker nodeHostnamemwt2-c203.campuscluster.illinois.edu
cpuinfoAMD EPYC 7402 24-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes10485760000 (10000 MiB)
Wall seconds limit86400 (24 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-12-18 20:33:51
Input filesusertests:pdhd_prod_beam__267378_61_1_20251212T160747Z_gen_g4_IonScintPDExt.root_262727_60_1_20251215T214749Z_PDInt.root
JobscriptExit code0
Real time1h (6330s)
CPU time1h (6095s = 96%)
Max RSS bytes6868299776 (6550 MiB)
Outputting started2025-12-18 22:19:22
Output filesusertests:pdhd_prod_beam__267378_61_1_20251212T160747Z_gen_g4_IonScintPDExt.root_262727_60_1_20251215T214749Z_PDInt.root_269119_87_1_20251218T203403Z_detsim_reco1.root
Finished2025-12-18 22:21:29
Saved logsjustin-logs:269119.87-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

ags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":20220 [0] "wiener0":22002 [22002] ] cmm:[ bad:0 lf_noisy:69 ] 
[16:18:57.710] D [  glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=179 time=0 tick=512 with 42222 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":20220 [0] "wiener0":22002 [22002] ] cmm:[ bad:0 lf_noisy:69 ] input 1: frame: ident=179 time=0 tick=512 with 28155 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":12252 [0] "wiener1":15903 [15903] ] cmm:[ bad:0 ] input 2: frame: ident=179 time=0 tick=512 with 37669 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":15717 [0] "wiener2":21952 [21952] ] cmm:[ bad:0 lf_noisy:1 ] input 3: frame: ident=179 time=0 tick=512 with 24172 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":11330 [0] "wiener3":12842 [12842] ] cmm:[ bad:0 ] output: frame: ident=179 time=0 tick=512 with 132218 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":20220 [0] "gauss1":12252 [0] "gauss2":15717 [0] "gauss3":11330 [0] "wiener0":22002 [22002] "wiener1":15903 [15903] "wiener2":21952 [21952] "wiener3":12842 [12842] ] cmm:[ bad:0 lf_noisy:70 ] 
Retagger: tagging trace set: wiener with 72699 traces, 72699 summary
Retagger: tagging trace set: gauss with 59519 traces, 0 summary
[16:18:57.724] D [  glue  ] sink frame: #179 @0 with 132218 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[16:18:57.724] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[16:18:57.724] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[16:18:57.724] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[16:18:57.724] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[16:18:57.724] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[16:18:57.724] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[16:18:57.724] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[16:18:57.724] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[16:18:57.724] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[16:18:57.724] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[16:18:57.724] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[16:18:57.724] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[16:18:57.724] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[16:18:57.724] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[16:18:57.724] D [  glue  ] frame sink sees EOS
[16:18:57.724] D [ pgraph ] <Pgrapher:> graph execution complete 
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.98 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.83 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.31 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.53 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.32 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.31 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.29 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.02 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:18:57.725] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[16:18:57.725] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[16:18:57.725] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[16:18:57.725] I [ timer  ] Timer: Total node execution : 49.60999991372228 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 59519 traces tagged "gauss"
FrameSaver: q=1.61046e+06 n=861677 tag=gauss
wclsFrameSaver: saving 72699 traces tagged "wiener"
FrameSaver: q=2.08535e+06 n=855754 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 5237 XUs and 5651 XVs -> 430 XUVs
C:0 T:4 0 XUs and 5 XVs -> 0 XUVs
C:0 T:5 18895 XUs and 34400 XVs -> 889 XUVs
C:0 T:6 2851 XUs and 3153 XVs -> 87 XUVs
1406 XUVs total
899 collection wire objects
1406 potential space points
Neighbour search...
13868 tests to find 8086 neighbours
Iterating with no regularization...
Begin: 3.14033e+07
0 3.00252e+07
1 3.00048e+07
Now with regularization...
Begin: 2.83412e+07
0 2.83398e+07
18-Dec-2025 16:19:20 CST  Closed output file "pdhd_prod_beam__267378_61_1_20251212T160747Z_gen_g4_IonScintPDExt.root_262727_60_1_20251215T214749Z_PDInt.root_269119_87_1_20251218T203403Z_detsim_reco1.root"
18-Dec-2025 16:19:20 CST  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           73.639        87.723        104.573       85.5344       9.23069        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                             0.00146232    0.00390717     0.0065134     0.0036295     0.0016344       10     
reco:wclsdatahd:WireCellToolkit                      48.504        51.3801       52.8647       51.6649       1.37616        10     
reco:wirefilter:WireFilter                          0.0180026     0.0246779     0.0309783     0.0256741    0.00369028       10     
reco:gaushit:GausHitFinder                            0.329       0.644192      0.934467      0.651143      0.168208        10     
reco:nhitsfilter:NumberOfHitsFilter                0.000119926   0.000342942   0.000771423   0.000326439   0.000160657      10     
reco:reco3d:SpacePointSolver                        0.216082      0.487722       0.98611      0.419203      0.206486        10     
reco:hitpdune:DisambigFromSpacePoints                0.2014       0.382181      0.694707       0.34665      0.164096        10     
reco:pandora:StandardPandora                         3.05546       7.3372        12.0689       7.58589       2.44973        10     
reco:pandoraWriter:StandardPandora                  0.159233      0.243017      0.327648      0.237617      0.0545036       10     
reco:pandoraTrack:LArPandoraTrackCreation           0.330802      0.761043       1.17519      0.769151      0.259209        10     
reco:pandoraShower:LArPandoraShowerCreation         0.260515       0.73902       1.44695       0.62069      0.394243        10     
reco:pandoracalo:Calorimetry                        0.205807      0.408025      0.593543      0.409145      0.125317        10     
reco:pandoracalonosce:Calorimetry                   0.195512      0.413948      0.638235      0.397253       0.15195        10     
reco:pandoraShowercalo:ShowerCalorimetry            0.269655      0.740805       1.29604      0.652626      0.369132        10     
reco:pandoraShowercalonosce:ShowerCalorimetry       0.260573      0.776732       1.4217       0.757853      0.405678        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0316029     0.0583207     0.0869669     0.0596049     0.0176041       10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0318927     0.057016      0.081051      0.0595772     0.0172173       10     
reco:emtrkmichelid:EmTrackMichelId                   15.1055       21.9162       29.2006       20.8856       4.84484        10     
[art]:TriggerResults:TriggerResultInserter         3.2882e-05    5.28768e-05   9.0431e-05    5.59505e-05   1.76629e-05      10     
end_path:out1:RootOutput                            9.217e-06    1.19906e-05   3.0487e-05    1.00185e-05   6.1873e-06       10     
end_path:out1:RootOutput(write)                     0.884199       1.28107       1.61773       1.34579      0.244027        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5959.65 MB
  Peak resident set size usage (VmHWM): 3768.14 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 11615596
-rw-r--r-- 1 dune osgvo     231269 Dec 18 16:19 jobscript.log
-rw-r--r-- 1 dune osgvo        200 Dec 18 16:19 justin-processed-pfns.txt
-rw-r--r-- 1 dune osgvo        519 Dec 18 16:19 reco_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo       3574 Dec 18 16:19 Pandora_Geometry.xml
-rw-r--r-- 1 dune osgvo     139264 Dec 18 16:19 mem.db
-rw-r--r-- 1 dune osgvo      28672 Dec 18 16:19 time.db
-rw-r--r-- 1 dune osgvo 6019024108 Dec 18 16:19 pdhd_prod_beam__267378_61_1_20251212T160747Z_gen_g4_IonScintPDExt.root_262727_60_1_20251215T214749Z_PDInt.root_269119_87_1_20251218T203403Z_detsim_reco1.root
-rw-r--r-- 1 dune osgvo   31687830 Dec 18 16:19 Pandora_Events.pndr
-rw-r--r-- 1 dune osgvo          0 Dec 18 16:02 debugprod.log
-rw-r--r-- 1 dune osgvo        519 Dec 18 16:02 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 5843199915 Dec 18 16:02 temp_detsim.root
-rw-r--r-- 1 dune osgvo        121 Dec 18 14:34 all-input-dids.txt
justIN time: 2025-12-19 13:10:06 UTC       justIN version: 01.05.03