justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 269119.76@dunegpschedd01.fnal.gov

Jobsub ID269119.76@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_uct2_condce_mcore
Last heartbeat2025-12-18 22:21:22
From worker nodeHostnameuct2-c510.mwt2.org
cpuinfoIntel(R) Xeon(R) Gold 6242 CPU @ 2.80GHz
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:18:51
Input filesusertests:pdhd_prod_beam__261612_114_1_20251212T160730Z_gen_g4_IonScintPDExt.root_268535_18_1_20251215T214536Z_PDInt.root
JobscriptExit code0
Real time2h (7231s)
CPU time1h (6982s = 96%)
Max RSS bytes11158863872 (10641 MiB)
Outputting started2025-12-18 22:19:23
Output filesusertests:pdhd_prod_beam__261612_114_1_20251212T160730Z_gen_g4_IonScintPDExt.root_268535_18_1_20251215T214536Z_PDInt.root_269119_76_1_20251218T201901Z_detsim_reco1.root
Finished2025-12-18 22:21:22
Saved logsjustin-logs:269119.76-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

18:48.214] D [  glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=179 time=0 tick=512 with 45319 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":21715 [0] "wiener0":23604 [23604] ] cmm:[ bad:0 lf_noisy:140 ] input 1: frame: ident=179 time=0 tick=512 with 38662 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":15980 [0] "wiener1":22682 [22682] ] cmm:[ bad:0 lf_noisy:7 ] input 2: frame: ident=179 time=0 tick=512 with 28505 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":13096 [0] "wiener2":15409 [15409] ] cmm:[ bad:0 ] input 3: frame: ident=179 time=0 tick=512 with 39359 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":17539 [0] "wiener3":21820 [21820] ] cmm:[ bad:0 ] output: frame: ident=179 time=0 tick=512 with 151845 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":21715 [0] "gauss1":15980 [0] "gauss2":13096 [0] "gauss3":17539 [0] "wiener0":23604 [23604] "wiener1":22682 [22682] "wiener2":15409 [15409] "wiener3":21820 [21820] ] cmm:[ bad:0 lf_noisy:147 ] 
Retagger: tagging trace set: wiener with 83515 traces, 83515 summary
Retagger: tagging trace set: gauss with 68330 traces, 0 summary
[16:18:48.243] D [  glue  ] sink frame: #179 @0 with 151845 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[16:18:48.243] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[16:18:48.243] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[16:18:48.243] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[16:18:48.243] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[16:18:48.243] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[16:18:48.243] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[16:18:48.243] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[16:18:48.243] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[16:18:48.243] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[16:18:48.243] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[16:18:48.243] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[16:18:48.243] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[16:18:48.243] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[16:18:48.243] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[16:18:48.243] D [  glue  ] frame sink sees EOS
[16:18:48.243] D [ pgraph ] <Pgrapher:> graph execution complete 
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 20.03 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 14.09 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 13.96 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 13 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 4.38 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.96 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.64 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.09 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.05 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:18:48.243] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[16:18:48.243] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[16:18:48.243] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[16:18:48.243] I [ timer  ] Timer: Total node execution : 76.24000104889274 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 68330 traces tagged "gauss"
FrameSaver: q=1.8932e+06 n=1074187 tag=gauss
wclsFrameSaver: saving 83515 traces tagged "wiener"
FrameSaver: q=2.39266e+06 n=1040893 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 22511 XUs and 15362 XVs -> 581 XUVs
C:0 T:3 13 XUs and 45 XVs -> 1 XUVs
C:0 T:4 5 XUs and 4 XVs -> 0 XUVs
C:0 T:5 33062 XUs and 75912 XVs -> 475 XUVs
C:0 T:6 21386 XUs and 17411 XVs -> 748 XUVs
C:0 T:7 4 XUs and 3 XVs -> 0 XUVs
1805 XUVs total
1010 collection wire objects
1805 potential space points
Neighbour search...
31417 tests to find 19166 neighbours
Iterating with no regularization...
Begin: 1.40098e+07
0 1.32664e+07
1 1.32139e+07
2 1.32064e+07
Now with regularization...
Begin: 1.24727e+07
0 1.24707e+07
18-Dec-2025 16:19:22 CST  Closed output file "pdhd_prod_beam__261612_114_1_20251212T160730Z_gen_g4_IonScintPDExt.root_268535_18_1_20251215T214536Z_PDInt.root_269119_76_1_20251218T201901Z_detsim_reco1.root"
18-Dec-2025 16:19:22 CST  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           107.527       154.565       348.381       125.949       70.3047        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                             0.00126284    0.00659551     0.0111309    0.00583069    0.00338557       10     
reco:wclsdatahd:WireCellToolkit                      71.5435       81.1892       92.4975       81.2932       5.6295         10     
reco:wirefilter:WireFilter                          0.0400999     0.0540451      0.08827      0.0510412     0.0133791       10     
reco:gaushit:GausHitFinder                          0.634471      0.992866       1.52538      0.952794      0.248997        10     
reco:nhitsfilter:NumberOfHitsFilter                0.000375485   0.000580914   0.000834986   0.000548099   0.000165034      10     
reco:reco3d:SpacePointSolver                        0.421436       3.40821       20.9607       1.5226        5.93185        10     
reco:hitpdune:DisambigFromSpacePoints               0.392304       1.00819       2.26339      0.851136      0.605559        10     
reco:pandora:StandardPandora                         7.99842       29.449        148.037       14.4381       40.9294        10     
reco:pandoraWriter:StandardPandora                  0.151287      0.308525      0.506368      0.272564      0.108666        10     
reco:pandoraTrack:LArPandoraTrackCreation            1.28907       3.75731       9.6893        2.8102        2.62388        10     
reco:pandoraShower:LArPandoraShowerCreation          1.27587       3.97917       10.411        2.42169       3.13661        10     
reco:pandoracalo:Calorimetry                        0.709467       1.94781       5.13731       1.18106       1.52831        10     
reco:pandoracalonosce:Calorimetry                   0.568277       1.80671       5.28632       1.15452       1.45269        10     
reco:pandoraShowercalo:ShowerCalorimetry             1.44861       3.84853       11.7294       2.06651       3.53533        10     
reco:pandoraShowercalonosce:ShowerCalorimetry        1.36717       3.59715       9.57805       2.36025       2.94064        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0556757     0.0904953     0.179529      0.0741265     0.038328        10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0528012     0.0855661      0.1411       0.0725476     0.0303022       10     
reco:emtrkmichelid:EmTrackMichelId                   11.2951       17.1532       30.0127       15.3545       5.43534        10     
[art]:TriggerResults:TriggerResultInserter         3.5889e-05    6.21338e-05   0.000105914   5.79145e-05   1.93863e-05      10     
end_path:out1:RootOutput                            5.161e-06    1.39413e-05   3.4445e-05    1.1557e-05    8.50095e-06      10     
end_path:out1:RootOutput(write)                      1.27976       1.79033       2.95234       1.54658      0.543043        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6991.07 MB
  Peak resident set size usage (VmHWM): 4615.6 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 14285756
-rw-r--r--. 1 dune osgvo     231601 Dec 18 16:19 jobscript.log
-rw-r--r--. 1 dune osgvo        201 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 7399882677 Dec 18 16:19 pdhd_prod_beam__261612_114_1_20251212T160730Z_gen_g4_IonScintPDExt.root_268535_18_1_20251215T214536Z_PDInt.root_269119_76_1_20251218T201901Z_detsim_reco1.root
-rw-r--r--. 1 dune osgvo   41846220 Dec 18 16:18 Pandora_Events.pndr
-rw-r--r--. 1 dune osgvo          0 Dec 18 15:51 debugprod.log
-rw-r--r--. 1 dune osgvo        519 Dec 18 15:50 detsim_single_protodunehd_hist.root
-rw-r--r--. 1 dune osgvo 7186436875 Dec 18 15:50 temp_detsim.root
-rw-r--r--. 1 dune osgvo        122 Dec 18 14:19 all-input-dids.txt
justIN time: 2025-12-19 13:10:11 UTC       justIN version: 01.05.03