justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 268965.0@dunegpschedd01.fnal.gov

Jobsub ID268965.0@dunegpschedd01.fnal.gov
Workflow ID11239
Stage ID1
User nameavizcaya@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
GPUNo
RSS bytes9437184000 (9000 MiB)
Wall seconds limit80000 (22 hours)
Submitted time2025-12-17 22:58:05
SiteUS_UChicago
EntryEngage_US_MWT2_iut2_gk02_condce_mcore
Last heartbeat2025-12-18 00:41:44
From worker nodeHostnameiut2-c370.iu.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 22:59:40
Input filesusertests:pdhd_prod_beam__267379_78_1_20251212T161846Z_gen_g4_IonScintPDExt.root_262726_21_1_20251215T212452Z_PDInt.root
JobscriptExit code0
Real time1h (6014s)
CPU time1h (5665s = 94%)
Max RSS bytes6936850432 (6615 MiB)
Outputting started2025-12-18 00:39:55
Output filesusertests:pdhd_prod_beam__267379_78_1_20251212T161846Z_gen_g4_IonScintPDExt.root_262726_21_1_20251215T212452Z_PDInt.root_268965_0_1_20251217T225954Z_detsim_reco1.root
Finished2025-12-18 00:41:44
Saved logsjustin-logs:268965.0-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

noisy:215 ] 
[19:39:02.622] D [  glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=189 time=0 tick=512 with 59553 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":28108 [0] "wiener0":31445 [31445] ] cmm:[ bad:0 lf_noisy:215 ] input 1: frame: ident=189 time=0 tick=512 with 59702 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":24671 [0] "wiener1":35031 [35031] ] cmm:[ bad:0 lf_noisy:6 ] input 2: frame: ident=189 time=0 tick=512 with 39882 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":17344 [0] "wiener2":22538 [22538] ] cmm:[ bad:0 ] input 3: frame: ident=189 time=0 tick=512 with 35274 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":15275 [0] "wiener3":19999 [19999] ] cmm:[ bad:0 lf_noisy:1 ] output: frame: ident=189 time=0 tick=512 with 194411 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":28108 [0] "gauss1":24671 [0] "gauss2":17344 [0] "gauss3":15275 [0] "wiener0":31445 [31445] "wiener1":35031 [35031] "wiener2":22538 [22538] "wiener3":19999 [19999] ] cmm:[ bad:0 lf_noisy:222 ] 
Retagger: tagging trace set: wiener with 109013 traces, 109013 summary
Retagger: tagging trace set: gauss with 85398 traces, 0 summary
[19:39:02.642] D [  glue  ] sink frame: #189 @0 with 194411 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[19:39:02.642] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[19:39:02.642] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[19:39:02.642] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[19:39:02.642] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[19:39:02.642] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[19:39:02.642] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[19:39:02.642] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[19:39:02.642] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[19:39:02.642] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[19:39:02.642] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[19:39:02.642] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[19:39:02.642] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[19:39:02.642] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[19:39:02.642] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[19:39:02.642] D [  glue  ] frame sink sees EOS
[19:39:02.642] D [ pgraph ] <Pgrapher:> graph execution complete 
[19:39:02.642] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.36 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.26 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.86 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.51 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.25 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.86 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.85 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.49 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:39:02.643] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[19:39:02.643] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[19:39:02.643] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[19:39:02.643] I [ timer  ] Timer: Total node execution : 47.49000005610287 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 85398 traces tagged "gauss"
FrameSaver: q=2.75962e+06 n=1343327 tag=gauss
wclsFrameSaver: saving 109013 traces tagged "wiener"
FrameSaver: q=3.71937e+06 n=1289524 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 69072 XUs and 73740 XVs -> 3280 XUVs
C:0 T:3 4 XUs and 8 XVs -> 0 XUVs
C:0 T:4 35 XUs and 66 XVs -> 14 XUVs
C:0 T:5 20647 XUs and 23695 XVs -> 1153 XUVs
C:0 T:6 20493 XUs and 20164 XVs -> 383 XUVs
4830 XUVs total
1964 collection wire objects
4830 potential space points
Neighbour search...
256858 tests to find 134510 neighbours
Iterating with no regularization...
Begin: 3.15625e+07
0 2.87643e+07
1 2.86487e+07
2 2.86315e+07
Now with regularization...
Begin: 2.68195e+07
0 2.68145e+07
17-Dec-2025 19:39:53 EST  Closed output file "pdhd_prod_beam__267379_78_1_20251212T161846Z_gen_g4_IonScintPDExt.root_262726_21_1_20251215T212452Z_PDInt.root_268965_0_1_20251217T225954Z_detsim_reco1.root"
17-Dec-2025 19:39:53 EST  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           69.3184       85.5786       99.4797       85.3157       8.77416        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                             0.00150766    0.00351902    0.00576604    0.00360853     0.0013214       10     
reco:wclsdatahd:WireCellToolkit                      45.9826       49.2811        53.98        49.1917       2.55155        10     
reco:wirefilter:WireFilter                          0.0143726     0.0198446     0.0358996     0.0190601    0.00617762       10     
reco:gaushit:GausHitFinder                          0.385091      0.548952       0.74901      0.546378      0.111568        10     
reco:nhitsfilter:NumberOfHitsFilter                0.000167678   0.000301396   0.000632078   0.00027439    0.000129965      10     
reco:reco3d:SpacePointSolver                        0.212849       0.5209        1.18222      0.367653      0.325465        10     
reco:hitpdune:DisambigFromSpacePoints               0.191777      0.374199      0.725804      0.288141       0.1785         10     
reco:pandora:StandardPandora                         3.20609       7.00452       11.9871       6.70849       2.48571        10     
reco:pandoraWriter:StandardPandora                  0.0947697     0.189551      0.293481      0.188256      0.0604439       10     
reco:pandoraTrack:LArPandoraTrackCreation           0.324023      0.816586       1.13948      0.903802      0.248977        10     
reco:pandoraShower:LArPandoraShowerCreation         0.162284      0.675709       1.28199      0.713936      0.294262        10     
reco:pandoracalo:Calorimetry                         0.1755       0.448486      0.623809      0.462145       0.12809        10     
reco:pandoracalonosce:Calorimetry                   0.221876      0.445124      0.592402      0.439677      0.115737        10     
reco:pandoraShowercalo:ShowerCalorimetry            0.131515      0.730412       1.32439      0.739171      0.330692        10     
reco:pandoraShowercalonosce:ShowerCalorimetry       0.132403      0.697046       1.32931      0.644845      0.314145        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0250895     0.0531601     0.0725005     0.0546391     0.0141544       10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0248225     0.0518945     0.072271      0.0516822     0.0144545       10     
reco:emtrkmichelid:EmTrackMichelId                   14.0767       22.3979       29.6252       22.4669       4.31035        10     
[art]:TriggerResults:TriggerResultInserter         2.6149e-05    4.88084e-05   0.000100218    4.027e-05    2.05653e-05      10     
end_path:out1:RootOutput                            7.625e-06    1.18883e-05   3.1299e-05    9.7875e-06    6.56719e-06      10     
end_path:out1:RootOutput(write)                     0.934529       1.26264       1.56106       1.31081      0.183939        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5869.89 MB
  Peak resident set size usage (VmHWM): 3693.02 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 11714488
-rw-r--r--. 1 dune osgvo     231222 Dec 17 19:39 jobscript.log
-rw-r--r--. 1 dune osgvo        200 Dec 17 19:39 justin-processed-pfns.txt
-rw-r--r--. 1 dune osgvo        519 Dec 17 19:39 reco_protodunehd_hist.root
-rw-r--r--. 1 dune osgvo       3574 Dec 17 19:39 Pandora_Geometry.xml
-rw-r--r--. 1 dune osgvo     139264 Dec 17 19:39 mem.db
-rw-r--r--. 1 dune osgvo      28672 Dec 17 19:39 time.db
-rw-r--r--. 1 dune osgvo 6071440569 Dec 17 19:39 pdhd_prod_beam__267379_78_1_20251212T161846Z_gen_g4_IonScintPDExt.root_262726_21_1_20251215T212452Z_PDInt.root_268965_0_1_20251217T225954Z_detsim_reco1.root
-rw-r--r--. 1 dune osgvo   31922020 Dec 17 19:39 Pandora_Events.pndr
-rw-r--r--. 1 dune osgvo          0 Dec 17 19:23 debugprod.log
-rw-r--r--. 1 dune osgvo        519 Dec 17 19:23 detsim_single_protodunehd_hist.root
-rw-r--r--. 1 dune osgvo 5891823129 Dec 17 19:23 temp_detsim.root
-rw-r--r--. 1 dune osgvo        121 Dec 17 17:59 all-input-dids.txt
justIN time: 2025-12-18 20:22:38 UTC       justIN version: 01.05.03