justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 262713.8@dunegpschedd02.fnal.gov

Jobsub ID262713.8@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-15 17:21:24
SiteUS_FNAL-T1
EntryCMSHTPC_T1_US_FNAL_condce_opp1_whole
Last heartbeat2025-12-15 22:01:51
From worker nodeHostnamedunegli-49162-0-cmswn4005.fnal.gov
cpuinfoAMD EPYC 7543 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-15 20:48:14
Input filesusertests:pdhd_prod_beam__259199_99_1_20251208T213354Z_gen_g4_IonScintPDExt.root_260263_67_1_20251210T194406Z_PDInt.root
JobscriptExit code0
Real time1h (4350s)
CPU time1h (3953s = 90%)
Max RSS bytes7716540416 (7359 MiB)
Outputting started2025-12-15 22:00:44
Output filesusertests:pdhd_prod_beam__259199_99_1_20251208T213354Z_gen_g4_IonScintPDExt.root_260263_67_1_20251210T194406Z_PDInt.root_262713_8_1_20251215T204818Z_detsim_reco1.root
Finished2025-12-15 22:01:51
Saved logsjustin-logs:262713.8-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=169 time=0 tick=512 with 69019 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":32278 [0] "wiener0":36741 [36741] ] cmm:[ bad:0 lf_noisy:242 ] input 1: frame: ident=169 time=0 tick=512 with 41862 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":18344 [0] "wiener1":23518 [23518] ] cmm:[ bad:0 ] input 2: frame: ident=169 time=0 tick=512 with 48770 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":21759 [0] "wiener2":27011 [27011] ] cmm:[ bad:0 lf_noisy:1 ] input 3: frame: ident=169 time=0 tick=512 with 39953 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":17265 [0] "wiener3":22688 [22688] ] cmm:[ bad:0 ] output: frame: ident=169 time=0 tick=512 with 199604 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":32278 [0] "gauss1":18344 [0] "gauss2":21759 [0] "gauss3":17265 [0] "wiener0":36741 [36741] "wiener1":23518 [23518] "wiener2":27011 [27011] "wiener3":22688 [22688] ] cmm:[ bad:0 lf_noisy:243 ] 
Retagger: tagging trace set: wiener with 109958 traces, 109958 summary
Retagger: tagging trace set: gauss with 89646 traces, 0 summary
[22:00:02.482] D [  glue  ] sink frame: #169 @0 with 199604 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[22:00:02.483] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[22:00:02.483] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[22:00:02.483] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[22:00:02.483] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[22:00:02.483] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[22:00:02.483] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[22:00:02.483] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[22:00:02.483] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[22:00:02.483] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[22:00:02.483] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[22:00:02.483] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[22:00:02.483] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[22:00:02.483] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[22:00:02.483] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[22:00:02.483] D [  glue  ] frame sink sees EOS
[22:00:02.483] D [ pgraph ] <Pgrapher:> graph execution complete 
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.09 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.68 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.55 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.48 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.95 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.92 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.9 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.9 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:00:02.483] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0 sec
[22:00:02.483] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[22:00:02.483] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[22:00:02.483] I [ timer  ] Timer: Total node execution : 32.480000147596 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 89646 traces tagged "gauss"
FrameSaver: q=3.02398e+06 n=1538628 tag=gauss
wclsFrameSaver: saving 109958 traces tagged "wiener"
FrameSaver: q=3.80482e+06 n=1488020 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 14742 XUs and 18925 XVs -> 696 XUVs
C:0 T:3 8 XUs and 24 XVs -> 3 XUVs
C:0 T:5 41333 XUs and 42132 XVs -> 1025 XUVs
C:0 T:6 23583 XUs and 24224 XVs -> 616 XUVs
C:0 T:7 9 XUs and 19 XVs -> 0 XUVs
2340 XUVs total
1762 collection wire objects
2340 potential space points
Neighbour search...
22264 tests to find 13476 neighbours
Iterating with no regularization...
Begin: 2.03834e+07
0 1.98228e+07
1 1.98014e+07
2 1.97977e+07
Now with regularization...
Begin: 1.83675e+07
0 1.83662e+07
15-Dec-2025 22:00:33 UTC  Closed output file "pdhd_prod_beam__259199_99_1_20251208T213354Z_gen_g4_IonScintPDExt.root_260263_67_1_20251210T194406Z_PDInt.root_262713_8_1_20251215T204818Z_detsim_reco1.root"
15-Dec-2025 22:00:34 UTC  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           50.553         58.05        68.8728       57.9603       5.74247        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                             0.000613082   0.00237174     0.0035762     0.0024596    0.000952919      10     
reco:wclsdatahd:WireCellToolkit                      31.9841       32.9492       34.2045       32.8596      0.712678        10     
reco:wirefilter:WireFilter                         0.00780667     0.0105726     0.0159216     0.0102624    0.00208593       10     
reco:gaushit:GausHitFinder                          0.260755      0.379576      0.507209      0.357832      0.0767704       10     
reco:nhitsfilter:NumberOfHitsFilter                0.000103867   0.000184027   0.000469701   0.000162047   9.89397e-05      10     
reco:reco3d:SpacePointSolver                        0.101865      0.253274       0.47355      0.252198      0.0996567       10     
reco:hitpdune:DisambigFromSpacePoints               0.116194      0.244248      0.394091      0.249902      0.0687534       10     
reco:pandora:StandardPandora                         2.29766       4.88222       8.34497       4.55229       1.8501         10     
reco:pandoraWriter:StandardPandora                  0.0786102     0.146777      0.552315       0.10103      0.135842        10     
reco:pandoraTrack:LArPandoraTrackCreation           0.314023       0.53356      0.847454      0.502278      0.141976        10     
reco:pandoraShower:LArPandoraShowerCreation         0.258674      0.584986       1.00626      0.585029      0.200518        10     
reco:pandoracalo:Calorimetry                        0.195563      0.326316       0.46735      0.320215      0.0718184       10     
reco:pandoracalonosce:Calorimetry                   0.189401      0.316169      0.466162       0.30505      0.0722494       10     
reco:pandoraShowercalo:ShowerCalorimetry            0.270873      0.621886       1.06036      0.620187      0.207814        10     
reco:pandoraShowercalonosce:ShowerCalorimetry       0.256182      0.611079       1.04684      0.609593      0.207841        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0210614     0.0287562     0.0393526     0.029927     0.00512787       10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0201405     0.0274662     0.0356934     0.028983     0.00449695       10     
reco:emtrkmichelid:EmTrackMichelId                   11.4531       15.046        19.8739       14.5743       2.5064         10     
[art]:TriggerResults:TriggerResultInserter         1.4859e-05    2.4801e-05    8.1114e-05    1.8249e-05    1.89476e-05      10     
end_path:out1:RootOutput                            6.973e-06    1.05449e-05   2.8093e-05    8.6915e-06    5.91494e-06      10     
end_path:out1:RootOutput(write)                     0.572046       1.0637        3.74039      0.761725      0.900146        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6127.32 MB
  Peak resident set size usage (VmHWM): 3872.26 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12745404
-rw-r--r-- 1 dunegli fnalgrid     230833 Dec 15 22:00 jobscript.log
-rw-r--r-- 1 dunegli fnalgrid        182 Dec 15 22:00 justin-processed-pfns.txt
-rw-r--r-- 1 dunegli fnalgrid        519 Dec 15 22:00 reco_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid       3574 Dec 15 22:00 Pandora_Geometry.xml
-rw-r--r-- 1 dunegli fnalgrid     139264 Dec 15 22:00 mem.db
-rw-r--r-- 1 dunegli fnalgrid      28672 Dec 15 22:00 time.db
-rw-r--r-- 1 dunegli fnalgrid 6607644128 Dec 15 22:00 pdhd_prod_beam__259199_99_1_20251208T213354Z_gen_g4_IonScintPDExt.root_260263_67_1_20251210T194406Z_PDInt.root_262713_8_1_20251215T204818Z_detsim_reco1.root
-rw-r--r-- 1 dunegli fnalgrid   37399690 Dec 15 22:00 Pandora_Events.pndr
-rw-r--r-- 1 dunegli fnalgrid          0 Dec 15 21:49 debugprod.log
-rw-r--r-- 1 dunegli fnalgrid        519 Dec 15 21:49 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 6405725167 Dec 15 21:49 temp_detsim.root
-rw-r--r-- 1 dunegli fnalgrid        121 Dec 15 20:48 all-input-dids.txt
justIN time: 2025-12-19 07:21:40 UTC       justIN version: 01.05.03