justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 263170.1@dunegpschedd02.fnal.gov

Jobsub ID263170.1@dunegpschedd02.fnal.gov
Workflow ID11235
Stage ID1
User nameavizcaya@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
GPUNo
RSS bytes9437184000 (9000 MiB)
Wall seconds limit80000 (22 hours)
Submitted time2025-12-17 23:06:05
SiteUS_UChicago
EntryEngage_US_MWT2_uiuc_condce_mcore
Last heartbeat2025-12-18 00:43:47
From worker nodeHostnamemwt2-c011.campuscluster.illinois.edu
cpuinfoAMD EPYC 7302 16-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-17 23:06:42
Input filesusertests:pdhd_prod_beam__261613_15_1_20251212T161224Z_gen_g4_IonScintPDExt.root_262728_157_1_20251215T220753Z_PDInt.root
JobscriptExit code0
Real time1h (5705s)
CPU time1h (5476s = 95%)
Max RSS bytes6838210560 (6521 MiB)
Outputting started2025-12-18 00:41:48
Output filesusertests:pdhd_prod_beam__261613_15_1_20251212T161224Z_gen_g4_IonScintPDExt.root_262728_157_1_20251215T220753Z_PDInt.root_263170_1_1_20251217T230649Z_detsim_reco1.root
Finished2025-12-18 00:43:47
Saved logsjustin-logs:263170.1-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

04 [0] "wiener0":23555 [23555] ] cmm:[ bad:0 lf_noisy:253 ] 
[18:41:18.664] D [  glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=199 time=0 tick=512 with 45059 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":21504 [0] "wiener0":23555 [23555] ] cmm:[ bad:0 lf_noisy:253 ] input 1: frame: ident=199 time=0 tick=512 with 29643 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":13079 [0] "wiener1":16564 [16564] ] cmm:[ bad:0 ] input 2: frame: ident=199 time=0 tick=512 with 37675 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":15941 [0] "wiener2":21734 [21734] ] cmm:[ bad:0 ] input 3: frame: ident=199 time=0 tick=512 with 42588 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":18248 [0] "wiener3":24340 [24340] ] cmm:[ bad:0 ] output: frame: ident=199 time=0 tick=512 with 154965 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":21504 [0] "gauss1":13079 [0] "gauss2":15941 [0] "gauss3":18248 [0] "wiener0":23555 [23555] "wiener1":16564 [16564] "wiener2":21734 [21734] "wiener3":24340 [24340] ] cmm:[ bad:0 lf_noisy:253 ] 
Retagger: tagging trace set: wiener with 86193 traces, 86193 summary
Retagger: tagging trace set: gauss with 68772 traces, 0 summary
[18:41:18.678] D [  glue  ] sink frame: #199 @0 with 154965 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[18:41:18.678] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[18:41:18.679] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[18:41:18.679] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[18:41:18.679] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[18:41:18.679] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[18:41:18.679] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[18:41:18.679] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[18:41:18.679] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[18:41:18.679] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[18:41:18.679] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[18:41:18.679] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[18:41:18.679] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[18:41:18.679] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[18:41:18.679] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[18:41:18.679] D [  glue  ] frame sink sees EOS
[18:41:18.679] D [ pgraph ] <Pgrapher:> graph execution complete 
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.1 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.24 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.88 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.47 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.19 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.09 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.03 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.49 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:41:18.679] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[18:41:18.679] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[18:41:18.679] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[18:41:18.679] I [ timer  ] Timer: Total node execution : 46.52000000886619 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 68772 traces tagged "gauss"
FrameSaver: q=2.14142e+06 n=1050698 tag=gauss
wclsFrameSaver: saving 86193 traces tagged "wiener"
FrameSaver: q=2.75829e+06 n=1033312 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 5588 XUs and 12731 XVs -> 483 XUVs
C:0 T:3 5 XUs and 21 XVs -> 0 XUVs
C:0 T:5 23616 XUs and 19376 XVs -> 963 XUVs
C:0 T:6 16443 XUs and 17935 XVs -> 955 XUVs
C:0 T:7 9 XUs and 10 XVs -> 0 XUVs
2401 XUVs total
1291 collection wire objects
2401 potential space points
Neighbour search...
52827 tests to find 34358 neighbours
Iterating with no regularization...
Begin: 2.98798e+07
0 2.87475e+07
1 2.86566e+07
2 2.86501e+07
Now with regularization...
Begin: 2.69476e+07
0 2.69454e+07
17-Dec-2025 18:41:47 CST  Closed output file "pdhd_prod_beam__261613_15_1_20251212T161224Z_gen_g4_IonScintPDExt.root_262728_157_1_20251215T220753Z_PDInt.root_263170_1_1_20251217T230649Z_detsim_reco1.root"
17-Dec-2025 18:41:47 CST  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           69.2297       87.0255       115.264       83.6303       13.4458        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                             0.00122164    0.00373501    0.00608397    0.00421566    0.00151286       10     
reco:wclsdatahd:WireCellToolkit                      48.511        50.8074       52.3356       50.8766       1.28558        10     
reco:wirefilter:WireFilter                          0.0112872     0.0181936     0.0275117     0.017444      0.0050178       10     
reco:gaushit:GausHitFinder                          0.329491      0.603721      0.944757      0.534428      0.182642        10     
reco:nhitsfilter:NumberOfHitsFilter                0.000129484   0.000262133   0.000501756   0.000243895   0.000104664      10     
reco:reco3d:SpacePointSolver                        0.129407      0.444825       1.44278      0.308106      0.362342        10     
reco:hitpdune:DisambigFromSpacePoints               0.130159      0.349061       0.67976      0.267628      0.195257        10     
reco:pandora:StandardPandora                         3.40936       7.71038       19.4473       4.93066       4.92038        10     
reco:pandoraWriter:StandardPandora                  0.117685      0.196291      0.314468      0.190545      0.0592571       10     
reco:pandoraTrack:LArPandoraTrackCreation            0.38519        0.706        1.02669      0.704054      0.218018        10     
reco:pandoraShower:LArPandoraShowerCreation         0.263324       0.78353       1.42823      0.747086      0.412884        10     
reco:pandoracalo:Calorimetry                        0.221953      0.405026      0.571631      0.411855      0.114834        10     
reco:pandoracalonosce:Calorimetry                   0.232475      0.374372      0.525529      0.363212      0.101243        10     
reco:pandoraShowercalo:ShowerCalorimetry            0.218146      0.793949       1.52116       0.70075      0.452548        10     
reco:pandoraShowercalonosce:ShowerCalorimetry       0.215104      0.800823        1.466       0.786547      0.443811        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0245002     0.0511765     0.0861147     0.0509137     0.0177727       10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0250593     0.0509588     0.0797691     0.0519673     0.016773        10     
reco:emtrkmichelid:EmTrackMichelId                   12.5158       21.6071       32.719        20.6286       5.49985        10     
[art]:TriggerResults:TriggerResultInserter         2.6239e-05    4.25123e-05   0.000107743   3.5437e-05    2.26689e-05      10     
end_path:out1:RootOutput                            8.926e-06    1.30917e-05   4.6578e-05     9.403e-06    1.11652e-05      10     
end_path:out1:RootOutput(write)                     0.736866       1.26954       1.79819       1.20705      0.317609        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5940.04 MB
  Peak resident set size usage (VmHWM): 3764.07 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 11426248
-rw-r--r-- 1 dune osgvo     231354 Dec 17 18:41 jobscript.log
-rw-r--r-- 1 dune osgvo        201 Dec 17 18:41 justin-processed-pfns.txt
-rw-r--r-- 1 dune osgvo        519 Dec 17 18:41 reco_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo       3574 Dec 17 18:41 Pandora_Geometry.xml
-rw-r--r-- 1 dune osgvo     139264 Dec 17 18:41 mem.db
-rw-r--r-- 1 dune osgvo      28672 Dec 17 18:41 time.db
-rw-r--r-- 1 dune osgvo 5923623258 Dec 17 18:41 pdhd_prod_beam__261613_15_1_20251212T161224Z_gen_g4_IonScintPDExt.root_262728_157_1_20251215T220753Z_PDInt.root_263170_1_1_20251217T230649Z_detsim_reco1.root
-rw-r--r-- 1 dune osgvo   32243110 Dec 17 18:41 Pandora_Events.pndr
-rw-r--r-- 1 dune osgvo          0 Dec 17 18:25 debugprod.log
-rw-r--r-- 1 dune osgvo        519 Dec 17 18:25 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 5744164988 Dec 17 18:25 temp_detsim.root
-rw-r--r-- 1 dune osgvo        122 Dec 17 17:06 all-input-dids.txt
justIN time: 2025-12-18 20:20:51 UTC       justIN version: 01.05.03