justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 263305.37@dunegpschedd02.fnal.gov

Jobsub ID263305.37@dunegpschedd02.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:31:07
SiteUS_FNAL-FermiGrid
EntryFNAL_GPGrid_ce04_mcore_op_duneonly
Last heartbeat2025-12-18 22:02:21
From worker nodeHostnamedunegli-8108645-0-fnpc22001.fnal.gov
cpuinfoAMD EPYC 7543 32-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes10485760000 (10000 MiB)
Wall seconds limit172800 (48 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-12-18 21:01:51
Input filesusertests:pdhd_prod_beam__261612_88_1_20251212T160302Z_gen_g4_IonScintPDExt.root_268535_42_1_20251215T215057Z_PDInt.root
JobscriptExit code0
Real time59m (3578s)
CPU time58m (3517s = 98%)
Max RSS bytes7573544960 (7222 MiB)
Outputting started2025-12-18 22:01:29
Output filesusertests:pdhd_prod_beam__261612_88_1_20251212T160302Z_gen_g4_IonScintPDExt.root_268535_42_1_20251215T215057Z_PDInt.root_263305_37_1_20251218T210154Z_detsim_reco1.root
Finished2025-12-18 22:02:21
Saved logsjustin-logs:263305.37-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

22:01:15.838] D [  glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=179 time=0 tick=512 with 44129 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":20755 [0] "wiener0":23374 [23374] ] cmm:[ bad:0 lf_noisy:174 ] input 1: frame: ident=179 time=0 tick=512 with 22743 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":10920 [0] "wiener1":11823 [11823] ] cmm:[ bad:0 ] input 2: frame: ident=179 time=0 tick=512 with 37255 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":16231 [0] "wiener2":21024 [21024] ] cmm:[ bad:0 ] input 3: frame: ident=179 time=0 tick=512 with 32928 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":14651 [0] "wiener3":18277 [18277] ] cmm:[ bad:0 ] output: frame: ident=179 time=0 tick=512 with 137055 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":20755 [0] "gauss1":10920 [0] "gauss2":16231 [0] "gauss3":14651 [0] "wiener0":23374 [23374] "wiener1":11823 [11823] "wiener2":21024 [21024] "wiener3":18277 [18277] ] cmm:[ bad:0 lf_noisy:174 ] 
Retagger: tagging trace set: wiener with 74498 traces, 74498 summary
Retagger: tagging trace set: gauss with 62557 traces, 0 summary
[22:01:15.842] D [  glue  ] sink frame: #179 @0 with 137055 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[22:01:15.842] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[22:01:15.842] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[22:01:15.842] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[22:01:15.842] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[22:01:15.842] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[22:01:15.842] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[22:01:15.842] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[22:01:15.842] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[22:01:15.842] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[22:01:15.842] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[22:01:15.842] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[22:01:15.842] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[22:01:15.842] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[22:01:15.842] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[22:01:15.842] D [  glue  ] frame sink sees EOS
[22:01:15.842] D [ pgraph ] <Pgrapher:> graph execution complete 
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.16 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.38 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.3 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.26 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.95 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.95 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.94 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.94 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:01:15.842] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[22:01:15.842] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[22:01:15.842] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[22:01:15.842] I [ timer  ] Timer: Total node execution : 31.89000059105456 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 62557 traces tagged "gauss"
FrameSaver: q=1.62873e+06 n=916273 tag=gauss
wclsFrameSaver: saving 74498 traces tagged "wiener"
FrameSaver: q=2.04594e+06 n=900813 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 2025 XUs and 1983 XVs -> 164 XUVs
C:0 T:4 7 XUs and 10 XVs -> 0 XUVs
C:0 T:5 57142 XUs and 40102 XVs -> 1650 XUVs
C:0 T:6 7032 XUs and 6621 XVs -> 431 XUVs
C:0 T:7 8 XUs and 2 XVs -> 0 XUVs
2245 XUVs total
962 collection wire objects
2245 potential space points
Neighbour search...
78849 tests to find 44298 neighbours
Iterating with no regularization...
Begin: 1.46749e+07
0 1.36947e+07
1 1.36324e+07
2 1.36213e+07
Now with regularization...
Begin: 1.28046e+07
0 1.27994e+07
18-Dec-2025 22:01:28 UTC  Closed output file "pdhd_prod_beam__261612_88_1_20251212T160302Z_gen_g4_IonScintPDExt.root_268535_42_1_20251215T215057Z_PDInt.root_263305_37_1_20251218T210154Z_detsim_reco1.root"
18-Dec-2025 22:01:28 UTC  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           45.2909       58.0375       80.5737       56.0505       9.88588        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                             0.000423905   0.00284474    0.00473054    0.00282191     0.0014196       10     
reco:wclsdatahd:WireCellToolkit                      32.685        33.8666       34.6484       33.8445      0.574689        10     
reco:wirefilter:WireFilter                         0.00699548    0.00999298     0.0135825    0.00984895    0.00196449       10     
reco:gaushit:GausHitFinder                           0.21662      0.365522      0.595871      0.351278      0.106219        10     
reco:nhitsfilter:NumberOfHitsFilter                7.9942e-05    0.000181194   0.000331009   0.000166235   7.34499e-05      10     
reco:reco3d:SpacePointSolver                        0.111869      0.366445      0.863689       0.25054      0.241386        10     
reco:hitpdune:DisambigFromSpacePoints               0.0771005     0.266574      0.636224      0.213091      0.184341        10     
reco:pandora:StandardPandora                         1.58858       4.89084       11.9564       4.38975       2.82953        10     
reco:pandoraWriter:StandardPandora                  0.0539509     0.110791       0.16504       0.1098       0.035951        10     
reco:pandoraTrack:LArPandoraTrackCreation           0.196234      0.479196      0.832556      0.425764      0.193542        10     
reco:pandoraShower:LArPandoraShowerCreation         0.145102       0.51537       1.35662      0.409566       0.34387        10     
reco:pandoracalo:Calorimetry                        0.130101      0.302898      0.570519      0.280867      0.128232        10     
reco:pandoracalonosce:Calorimetry                   0.124529      0.290414      0.548107      0.270368      0.122634        10     
reco:pandoraShowercalo:ShowerCalorimetry            0.142419      0.570504       1.72344      0.455722      0.437274        10     
reco:pandoraShowercalonosce:ShowerCalorimetry        0.14479      0.561535       1.71063       0.44717      0.432082        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0160576     0.0278645     0.0522956     0.0254352     0.0103753       10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0157048     0.0268514     0.0517452     0.0251647     0.0100148       10     
reco:emtrkmichelid:EmTrackMichelId                   8.62895       14.6087       24.1975       14.1823       4.55504        10     
[art]:TriggerResults:TriggerResultInserter         1.3997e-05    2.48901e-05   6.7227e-05    2.12755e-05   1.47086e-05      10     
end_path:out1:RootOutput                            3.517e-06    6.8038e-06    2.8073e-05     3.847e-06    7.2844e-06       10     
end_path:out1:RootOutput(write)                     0.464863      0.748069       1.18357      0.711931       0.20579        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6044.51 MB
  Peak resident set size usage (VmHWM): 3887.19 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12331052
-rw-r--r-- 1 dunegli fnalgrid     231440 Dec 18 22:01 jobscript.log
-rw-r--r-- 1 dunegli fnalgrid        200 Dec 18 22:01 justin-processed-pfns.txt
-rw-r--r-- 1 dunegli fnalgrid        519 Dec 18 22:01 reco_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid       3574 Dec 18 22:01 Pandora_Geometry.xml
-rw-r--r-- 1 dunegli fnalgrid     139264 Dec 18 22:01 mem.db
-rw-r--r-- 1 dunegli fnalgrid      28672 Dec 18 22:01 time.db
-rw-r--r-- 1 dunegli fnalgrid 6392733386 Dec 18 22:01 pdhd_prod_beam__261612_88_1_20251212T160302Z_gen_g4_IonScintPDExt.root_268535_42_1_20251215T215057Z_PDInt.root_263305_37_1_20251218T210154Z_detsim_reco1.root
-rw-r--r-- 1 dunegli fnalgrid   35833950 Dec 18 22:01 Pandora_Events.pndr
-rw-r--r-- 1 dunegli fnalgrid          0 Dec 18 21:50 debugprod.log
-rw-r--r-- 1 dunegli fnalgrid        519 Dec 18 21:50 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 6197903535 Dec 18 21:50 temp_detsim.root
-rw-r--r-- 1 dunegli fnalgrid        121 Dec 18 21:01 all-input-dids.txt
justIN time: 2025-12-18 23:05:57 UTC       justIN version: 01.05.03