justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 233301.1@dunegpschedd01.fnal.gov

Jobsub ID233301.1@dunegpschedd01.fnal.gov
Workflow ID8928
Stage ID1
User namejjo@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
GPUNo
RSS bytes8387559424 (7999 MiB)
Wall seconds limit80000 (22 hours)
Submitted time2025-10-13 13:53:54
SiteUS_FNAL-FermiGrid
EntryFNAL_GPGrid_ce03_mcore_op_duneonly
Last heartbeat2025-10-13 17:37:45
From worker nodeHostnamedunegli-7231092-0-fnpc22020.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-10-13 13:57:43
Input filesmonte-carlo-008928-000002
JobscriptExit code0
Real time3h (13177s)
CPU time3h (12985s = 98%)
Max RSS bytes6949593088 (6627 MiB)
Outputting started2025-10-13 17:37:21
Output fileshttps://fndcadoor.fnal.gov:2880/dune/scratch/users/jjo/fnal/08928/1/001/monte-carlo-008928-000002_233301_1_1_20251013T135745Z_reco.root
Finished2025-10-13 17:37:44
Saved logsjustin-logs:233301.1-dunegpschedd01.fnal.gov.logs.tgz
List job events     (HTCondor job logs unavailable)

Jobscript log (last 10,000 characters)

mnibusSigProc:anode0sigproc0> call=38 save plane index: 0, Qtot=82599515 Qloss=-9232988, 1803 indices spanning [8148,9950] "gauss" 
[17:37:18.137] D [sigproc ] <OmnibusSigProc:anode0sigproc0> call=38 save plane index: 1, Qtot=85900901 Qloss=-11469591, 2316 indices spanning [9951,12266] "wiener" 
[17:37:18.219] D [sigproc ] <OmnibusSigProc:anode0sigproc0> call=38 save plane index: 1, Qtot=81039251 Qloss=-8544867, 2023 indices spanning [12267,14289] "gauss" 
[17:37:18.367] D [sigproc ] <OmnibusSigProc:anode0sigproc0> call=38 save plane index: 2, Qtot=79889907 Qloss=-3547055, 2206 indices spanning [14290,16495] "wiener" 
[17:37:18.517] D [sigproc ] <OmnibusSigProc:anode0sigproc0> call=38 save plane index: 2, Qtot=78437270 Qloss=-2102250, 1879 indices spanning [16496,18374] "gauss" 
[17:37:18.519] D [sigproc ] <OmnibusSigProc:anode0sigproc0> call=38 produce 18375 traces: 6566 wiener0, 0 decon_charge0, 5705 gauss0, frame tag: sigproc 
[17:37:18.519] D [sigproc ] <OmnibusSigProc:anode0sigproc0> call=38 output frame: frame: ident=20 time=13 tick=500 with 18375 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss0":5705 [0] "mp2_roi0":4582 [0] "mp3_roi0":1522 [0] "wiener0":6566 [6566] ] cmm:[ ] 
[17:37:18.534] D [  glue  ] <FrameFanin:nfsp> call=38 input 0: frame: ident=20 time=13 tick=500 with 18375 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss0":5705 [0] "mp2_roi0":4582 [0] "mp3_roi0":1522 [0] "wiener0":6566 [6566] ] cmm:[ ] input 1: frame: ident=20 time=13 tick=500 with 11128 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss1":3666 [0] "mp2_roi1":2378 [0] "mp3_roi1":827 [0] "wiener1":4257 [4257] ] cmm:[ ] input 2: frame: ident=20 time=13 tick=500 with 22454 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss2":6737 [0] "mp2_roi2":5948 [0] "mp3_roi2":1948 [0] "wiener2":7821 [7821] ] cmm:[ ] input 3: frame: ident=20 time=13 tick=500 with 20154 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss3":5913 [0] "mp2_roi3":4971 [0] "mp3_roi3":1811 [0] "wiener3":7459 [7459] ] cmm:[ ] input 4: frame: ident=20 time=13 tick=500 with 15491 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss4":4841 [0] "mp2_roi4":3298 [0] "mp3_roi4":1386 [0] "wiener4":5966 [5966] ] cmm:[ ] input 5: frame: ident=20 time=13 tick=500 with 15794 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss5":4918 [0] "mp2_roi5":3273 [0] "mp3_roi5":1385 [0] "wiener5":6218 [6218] ] cmm:[ ] input 6: frame: ident=20 time=13 tick=500 with 19456 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss6":5289 [0] "mp2_roi6":5966 [0] "mp3_roi6":1610 [0] "wiener6":6591 [6591] ] cmm:[ ] input 7: frame: ident=20 time=13 tick=500 with 14108 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss7":4658 [0] "mp2_roi7":2646 [0] "mp3_roi7":1213 [0] "wiener7":5591 [5591] ] cmm:[ ] output: frame: ident=20 time=13 tick=500 with 136960 traces.  frame tags:[ "framefanin" ] 16 tagged trace sets:[ "gauss0":5705 [0] "gauss1":3666 [0] "gauss2":6737 [0] "gauss3":5913 [0] "gauss4":4841 [0] "gauss5":4918 [0] "gauss6":5289 [0] "gauss7":4658 [0] "wiener0":6566 [6566] "wiener1":4257 [4257] "wiener2":7821 [7821] "wiener3":7459 [7459] "wiener4":5966 [5966] "wiener5":6218 [6218] "wiener6":6591 [6591] "wiener7":5591 [5591] ] cmm:[ ] 
Retagger: tagging trace set: wiener with 50469 traces, 50469 summary
Retagger: tagging trace set: gauss with 41727 traces, 0 summary
[17:37:18.537] D [  glue  ] sink frame: #20 @1.3e-05 with 136960 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[17:37:18.537] D [  glue  ] <FrameFanout:nfsp> call=39: see EOS 
[17:37:18.537] D [  glue  ] <ChannelSelector:chsel7> see EOS at call=19 
[17:37:18.537] D [sigproc ] <OmnibusSigProc:anode7sigproc7> EOS at call=39 anode=7 
[17:37:18.537] D [  glue  ] <ChannelSelector:chsel6> see EOS at call=19 
[17:37:18.537] D [sigproc ] <OmnibusSigProc:anode6sigproc6> EOS at call=39 anode=6 
[17:37:18.538] D [  glue  ] <ChannelSelector:chsel5> see EOS at call=19 
[17:37:18.538] D [sigproc ] <OmnibusSigProc:anode5sigproc5> EOS at call=39 anode=5 
[17:37:18.538] D [  glue  ] <ChannelSelector:chsel4> see EOS at call=19 
[17:37:18.538] D [sigproc ] <OmnibusSigProc:anode4sigproc4> EOS at call=39 anode=4 
[17:37:18.538] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=19 
[17:37:18.538] D [sigproc ] <OmnibusSigProc:anode3sigproc3> EOS at call=39 anode=3 
[17:37:18.538] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=19 
[17:37:18.538] D [sigproc ] <OmnibusSigProc:anode2sigproc2> EOS at call=39 anode=2 
[17:37:18.538] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=19 
[17:37:18.538] D [sigproc ] <OmnibusSigProc:anode1sigproc1> EOS at call=39 anode=1 
[17:37:18.538] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=19 
[17:37:18.538] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=39 anode=0 
[17:37:18.538] D [  glue  ] <FrameFanin:nfsp> EOS at call=39 with 8 
[17:37:18.538] D [  glue  ] frame sink sees EOS
[17:37:18.538] D [ pgraph ] <Pgrapher:> graph execution complete 
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 4.8 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 4.27 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 4.14 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 3.97 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 3.83 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 3.77 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 3.49 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 3.32 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:18.538] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:37:18.538] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[17:37:18.538] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[17:37:18.538] I [ timer  ] Timer: Total node execution : 31.609999913722277 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 41727 traces tagged "gauss"
FrameSaver: q=9.35637e+06 n=728635 tag=gauss
wclsFrameSaver: saving 50469 traces tagged "wiener"
FrameSaver: q=9.76335e+06 n=698887 tag=wiener
13-Oct-2025 17:37:20 UTC  Closed output file "monte-carlo-008928-000002_233301_1_1_20251013T135745Z_reco.root"
13-Oct-2025 17:37:20 UTC  Closed input file "detsim.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                      26.5117       29.2656       33.3811       29.0472       1.85546        20     
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.000512658    0.001685      0.0024654    0.00184648    0.000533289      20     
reco:rns:RandomNumberSaver                    3.2582e-05    4.62463e-05   0.000228401   3.50815e-05   4.19746e-05      20     
reco:wclsdatavd:WireCellToolkit                 25.7637       28.4211       32.4695       28.2091       1.77563        20     
reco:gaushit:GausHitFinder                     0.406751      0.623801       1.26025      0.580703      0.183325        20     
[art]:TriggerResults:TriggerResultInserter    1.2594e-05    1.90631e-05   4.6908e-05    1.7383e-05    7.24891e-06      20     
end_path:out1:RootOutput                       2.695e-06    6.68615e-06   1.8565e-05    4.6935e-06    4.36517e-06      20     
end_path:out1:RootOutput(write)                0.149113      0.216387      0.331248      0.216179      0.0423685       20     
================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5106.55 MB
  Peak resident set size usage (VmHWM): 3407.45 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Job completed successfully.
-rw-r--r-- 1 dunegli fnalgrid 3.5G Oct 13 17:25 detsim.root
-rw-r--r-- 1 dunegli fnalgrid  519 Oct 13 17:25 detsim_single_protoDUNE_hist.root
-rw-r--r-- 1 dunegli fnalgrid  519 Oct 13 14:14 g4_protoDUNE_hist.root
-rw-r--r-- 1 dunegli fnalgrid 5.1G Oct 13 14:07 g4_stage1.root
-rw-r--r-- 1 dunegli fnalgrid  12G Oct 13 14:14 g4_stage2.root
-rw-r--r-- 1 dunegli fnalgrid 973K Oct 13 13:58 gen.root
-rw-r--r-- 1 dunegli fnalgrid  519 Oct 13 13:58 gen_protodunevd_cosmics_hist.root
-rw-r--r-- 1 dunegli fnalgrid 3.6G Oct 13 17:37 monte-carlo-008928-000002_233301_1_1_20251013T135745Z_reco.root
-rw-r--r-- 1 dunegli fnalgrid  519 Oct 13 17:37 reco_protoDUNE_hist.root
justIN time: 2025-11-04 07:41:49 UTC       justIN version: 01.05.01