justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 263132.1@dunegpschedd02.fnal.gov

Jobsub ID263132.1@dunegpschedd02.fnal.gov
Workflow ID11228
Stage ID1
User nameavizcaya@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
GPUNo
RSS bytes9437184000 (9000 MiB)
Wall seconds limit80000 (22 hours)
Submitted time2025-12-17 21:22:00
SiteUS_FNAL-T1
EntryCMSHTPC_T1_US_FNAL_condce_opp1_whole
Last heartbeat2025-12-17 23:05:43
From worker nodeHostnamedunegli-49199-0-cmswn2413.fnal.gov
cpuinfoAMD EPYC 7551 32-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes10485760000 (10000 MiB)
Wall seconds limit171000 (47 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-12-17 21:23:04
Input filesusertests:pdhd_prod_beam__259199_36_1_20251208T212634Z_gen_g4_IonScintPDExt.root_266057_47_1_20251210T192243Z_PDInt.root
JobscriptExit code0
Real time1h (6083s)
CPU time1h (5979s = 98%)
Max RSS bytes7659704320 (7304 MiB)
Outputting started2025-12-17 23:04:27
Output filesusertests:pdhd_prod_beam__259199_36_1_20251208T212634Z_gen_g4_IonScintPDExt.root_266057_47_1_20251210T192243Z_PDInt.root_263132_1_1_20251217T212309Z_detsim_reco1.root
Finished2025-12-17 23:05:43
Saved logsjustin-logs:263132.1-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

":25745 [25745] ] cmm:[ bad:0 lf_noisy:228 ] 
[23:03:43.132] D [  glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=169 time=0 tick=512 with 49197 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":23452 [0] "wiener0":25745 [25745] ] cmm:[ bad:0 lf_noisy:228 ] input 1: frame: ident=169 time=0 tick=512 with 37755 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":16167 [0] "wiener1":21588 [21588] ] cmm:[ bad:0 ] input 2: frame: ident=169 time=0 tick=512 with 54685 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":24142 [0] "wiener2":30543 [30543] ] cmm:[ bad:0 lf_noisy:1 ] input 3: frame: ident=169 time=0 tick=512 with 24109 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":11563 [0] "wiener3":12546 [12546] ] cmm:[ bad:0 ] output: frame: ident=169 time=0 tick=512 with 165746 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":23452 [0] "gauss1":16167 [0] "gauss2":24142 [0] "gauss3":11563 [0] "wiener0":25745 [25745] "wiener1":21588 [21588] "wiener2":30543 [30543] "wiener3":12546 [12546] ] cmm:[ bad:0 lf_noisy:229 ] 
Retagger: tagging trace set: wiener with 90422 traces, 90422 summary
Retagger: tagging trace set: gauss with 75324 traces, 0 summary
[23:03:43.143] D [  glue  ] sink frame: #169 @0 with 165746 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[23:03:43.143] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[23:03:43.143] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[23:03:43.143] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[23:03:43.143] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[23:03:43.143] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[23:03:43.143] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[23:03:43.143] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[23:03:43.143] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[23:03:43.143] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[23:03:43.143] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[23:03:43.144] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[23:03:43.144] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[23:03:43.144] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[23:03:43.144] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[23:03:43.144] D [  glue  ] frame sink sees EOS
[23:03:43.144] D [ pgraph ] <Pgrapher:> graph execution complete 
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.23 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.58 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.46 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.04 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.78 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.77 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.77 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.75 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:03:43.144] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[23:03:43.144] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[23:03:43.144] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[23:03:43.144] I [ timer  ] Timer: Total node execution : 48.40999939851463 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 75324 traces tagged "gauss"
FrameSaver: q=2.18823e+06 n=1198114 tag=gauss
wclsFrameSaver: saving 90422 traces tagged "wiener"
FrameSaver: q=2.75406e+06 n=1175299 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 52240 XUs and 32798 XVs -> 1488 XUVs
C:0 T:5 74843 XUs and 46419 XVs -> 1419 XUVs
C:0 T:6 5957 XUs and 4987 XVs -> 312 XUVs
3219 XUVs total
1731 collection wire objects
3219 potential space points
Neighbour search...
71941 tests to find 37494 neighbours
Iterating with no regularization...
Begin: 1.82015e+07
0 1.63932e+07
1 1.63139e+07
2 1.6307e+07
Now with regularization...
Begin: 1.51192e+07
0 1.51167e+07
17-Dec-2025 23:04:25 UTC  Closed output file "pdhd_prod_beam__259199_36_1_20251208T212634Z_gen_g4_IonScintPDExt.root_266057_47_1_20251210T192243Z_PDInt.root_263132_1_1_20251217T212309Z_detsim_reco1.root"
17-Dec-2025 23:04:25 UTC  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           74.7621       95.2223       130.431       93.8963       14.9343        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                             0.000755685   0.00301828    0.00432852    0.00340779    0.00113681       10     
reco:wclsdatahd:WireCellToolkit                      43.5607       46.7594       49.4802       46.7058       2.00799        10     
reco:wirefilter:WireFilter                          0.0130908     0.0177671     0.0245927     0.0166094    0.00385158       10     
reco:gaushit:GausHitFinder                          0.365269      0.652777       1.23819      0.599776      0.222003        10     
reco:nhitsfilter:NumberOfHitsFilter                0.000180121   0.000316878   0.000487553   0.000309737   8.77757e-05      10     
reco:reco3d:SpacePointSolver                        0.142002      0.479471      0.974088      0.511719      0.226245        10     
reco:hitpdune:DisambigFromSpacePoints               0.131176      0.433992      0.930273      0.404163       0.22765        10     
reco:pandora:StandardPandora                         4.07392       10.0662       29.0293       8.39615       6.67949        10     
reco:pandoraWriter:StandardPandora                  0.117382      0.187677      0.247605      0.181501      0.0362117       10     
reco:pandoraTrack:LArPandoraTrackCreation           0.501068      0.963814       1.97223      0.899947      0.415527        10     
reco:pandoraShower:LArPandoraShowerCreation         0.354071       1.06533       1.80021       1.03305      0.477337        10     
reco:pandoracalo:Calorimetry                        0.251493      0.485898      0.880349      0.460015      0.179688        10     
reco:pandoracalonosce:Calorimetry                   0.243511      0.472523      0.839083      0.448494      0.172109        10     
reco:pandoraShowercalo:ShowerCalorimetry            0.311166      0.920003       1.38683      0.952609      0.366849        10     
reco:pandoraShowercalonosce:ShowerCalorimetry       0.304848      0.920608       1.36954      0.943248      0.371088        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0312045     0.0485429     0.0666919     0.0493475     0.0099491       10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0310139     0.0478157     0.0658811     0.049643      0.010059        10     
reco:emtrkmichelid:EmTrackMichelId                   18.6491       30.4081       42.7246       29.473        6.93472        10     
[art]:TriggerResults:TriggerResultInserter          1.612e-05    2.91672e-05    8.319e-05    2.38055e-05   1.82512e-05      10     
end_path:out1:RootOutput                            7.19e-06      9.723e-06     2.722e-05     7.775e-06    5.84952e-06      10     
end_path:out1:RootOutput(write)                     0.849653       1.24191       1.58476       1.25545      0.221314        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6639.33 MB
  Peak resident set size usage (VmHWM): 4423.24 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12585424
-rw-r--r-- 1 dunegli fnalgrid     231278 Dec 17 23:04 jobscript.log
-rw-r--r-- 1 dunegli fnalgrid        200 Dec 17 23:04 justin-processed-pfns.txt
-rw-r--r-- 1 dunegli fnalgrid        519 Dec 17 23:04 reco_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid       3574 Dec 17 23:04 Pandora_Geometry.xml
-rw-r--r-- 1 dunegli fnalgrid     139264 Dec 17 23:04 mem.db
-rw-r--r-- 1 dunegli fnalgrid      28672 Dec 17 23:04 time.db
-rw-r--r-- 1 dunegli fnalgrid 6525126245 Dec 17 23:04 pdhd_prod_beam__259199_36_1_20251208T212634Z_gen_g4_IonScintPDExt.root_266057_47_1_20251210T192243Z_PDInt.root_263132_1_1_20251217T212309Z_detsim_reco1.root
-rw-r--r-- 1 dunegli fnalgrid   38703410 Dec 17 23:03 Pandora_Events.pndr
-rw-r--r-- 1 dunegli fnalgrid          0 Dec 17 22:46 debugprod.log
-rw-r--r-- 1 dunegli fnalgrid        519 Dec 17 22:46 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 6323194713 Dec 17 22:46 temp_detsim.root
-rw-r--r-- 1 dunegli fnalgrid        121 Dec 17 21:23 all-input-dids.txt
justIN time: 2025-12-18 20:20:56 UTC       justIN version: 01.05.03