justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 263305.8@dunegpschedd02.fnal.gov

Jobsub ID263305.8@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_UChicago
EntryEngage_US_MWT2_uiuc_condce_mcore
Last heartbeat2025-12-18 21:48:34
From worker nodeHostnamemwt2-c091.campuscluster.illinois.edu
cpuinfoAMD EPYC 7443 24-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-18 20:08:01
Input filesusertests:pdhd_prod_beam__261612_108_1_20251212T160411Z_gen_g4_IonScintPDExt.root_262727_33_1_20251215T214335Z_PDInt.root
JobscriptExit code0
Real time1h (5923s)
CPU time1h (5549s = 93%)
Max RSS bytes7868137472 (7503 MiB)
Outputting started2025-12-18 21:46:44
Output filesusertests:pdhd_prod_beam__261612_108_1_20251212T160411Z_gen_g4_IonScintPDExt.root_262727_33_1_20251215T214335Z_PDInt.root_263305_8_1_20251218T200809Z_detsim_reco1.root
Finished2025-12-18 21:48:34
Saved logsjustin-logs:263305.8-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

trace sets:[ "gauss0":24290 [0] "wiener0":26967 [26967] ] cmm:[ bad:0 lf_noisy:361 ] 
[15:46:08.919] D [  glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=179 time=0 tick=512 with 51257 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":24290 [0] "wiener0":26967 [26967] ] cmm:[ bad:0 lf_noisy:361 ] input 1: frame: ident=179 time=0 tick=512 with 35159 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":15543 [0] "wiener1":19616 [19616] ] cmm:[ bad:0 ] input 2: frame: ident=179 time=0 tick=512 with 54596 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":23279 [0] "wiener2":31317 [31317] ] cmm:[ bad:0 ] input 3: frame: ident=179 time=0 tick=512 with 33693 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":15147 [0] "wiener3":18546 [18546] ] cmm:[ bad:0 lf_noisy:1 ] output: frame: ident=179 time=0 tick=512 with 174705 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":24290 [0] "gauss1":15543 [0] "gauss2":23279 [0] "gauss3":15147 [0] "wiener0":26967 [26967] "wiener1":19616 [19616] "wiener2":31317 [31317] "wiener3":18546 [18546] ] cmm:[ bad:0 lf_noisy:362 ] 
Retagger: tagging trace set: wiener with 96446 traces, 96446 summary
Retagger: tagging trace set: gauss with 78259 traces, 0 summary
[15:46:08.928] D [  glue  ] sink frame: #179 @0 with 174705 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[15:46:08.928] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[15:46:08.928] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[15:46:08.928] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[15:46:08.928] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[15:46:08.928] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[15:46:08.928] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[15:46:08.928] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[15:46:08.928] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[15:46:08.928] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[15:46:08.928] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[15:46:08.928] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[15:46:08.928] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[15:46:08.928] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[15:46:08.928] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[15:46:08.928] D [  glue  ] frame sink sees EOS
[15:46:08.928] D [ pgraph ] <Pgrapher:> graph execution complete 
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.16 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.18 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.37 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.13 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.86 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.31 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.22 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.14 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[15:46:08.928] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[15:46:08.928] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[15:46:08.928] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[15:46:08.928] I [ timer  ] Timer: Total node execution : 39.380000123754144 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 78259 traces tagged "gauss"
FrameSaver: q=2.53415e+06 n=1300276 tag=gauss
wclsFrameSaver: saving 96446 traces tagged "wiener"
FrameSaver: q=3.10764e+06 n=1257761 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 10019 XUs and 11825 XVs -> 846 XUVs
C:0 T:4 33 XUs and 22 XVs -> 1 XUVs
C:0 T:5 60775 XUs and 38510 XVs -> 1232 XUVs
C:0 T:6 13993 XUs and 7898 XVs -> 251 XUVs
2330 XUVs total
1485 collection wire objects
2330 potential space points
Neighbour search...
31288 tests to find 19508 neighbours
Iterating with no regularization...
Begin: 2.76289e+07
0 2.68222e+07
1 2.67847e+07
2 2.67807e+07
Now with regularization...
Begin: 2.5268e+07
0 2.52664e+07
18-Dec-2025 15:46:42 CST  Closed output file "pdhd_prod_beam__261612_108_1_20251212T160411Z_gen_g4_IonScintPDExt.root_262727_33_1_20251215T214335Z_PDInt.root_263305_8_1_20251218T200809Z_detsim_reco1.root"
18-Dec-2025 15:46:42 CST  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           64.094        81.5577       104.466       80.581        10.9842        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                             0.00122601     0.0038035    0.00548113    0.00436363    0.00141356       10     
reco:wclsdatahd:WireCellToolkit                      39.9323       42.9188       45.7654       42.8401       1.99313        10     
reco:wirefilter:WireFilter                          0.0101259     0.0170646     0.0274359     0.0173333    0.00466267       10     
reco:gaushit:GausHitFinder                          0.327985       0.62933      0.890445      0.614418      0.185301        10     
reco:nhitsfilter:NumberOfHitsFilter                0.000175549   0.000262885   0.000490911   0.00025577    8.55056e-05      10     
reco:reco3d:SpacePointSolver                        0.175498      0.451634       1.02285      0.373058       0.26394        10     
reco:hitpdune:DisambigFromSpacePoints                0.13009      0.397058      0.671675      0.414288       0.14616        10     
reco:pandora:StandardPandora                         3.99405       8.18848       14.6181       8.02054       2.86629        10     
reco:pandoraWriter:StandardPandora                  0.113486      0.190661      0.326266      0.173362      0.065798        10     
reco:pandoraTrack:LArPandoraTrackCreation           0.294014      0.728301       1.16675      0.684741      0.244712        10     
reco:pandoraShower:LArPandoraShowerCreation          0.40968      0.878644       1.33708      0.874025      0.272034        10     
reco:pandoracalo:Calorimetry                        0.274848      0.499063       0.68015      0.517425      0.135609        10     
reco:pandoracalonosce:Calorimetry                   0.256282      0.498297      0.720851       0.50896       0.13374        10     
reco:pandoraShowercalo:ShowerCalorimetry            0.353917      0.972523       1.78592       1.01985      0.413463        10     
reco:pandoraShowercalonosce:ShowerCalorimetry       0.324538      0.913375       1.58194      0.929317      0.366121        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0287801     0.0538281     0.078334      0.0515652     0.016401        10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0245137     0.0508456     0.0754514     0.0488995     0.017092        10     
reco:emtrkmichelid:EmTrackMichelId                   16.2937       22.8536       32.3561       22.4781       4.38544        10     
[art]:TriggerResults:TriggerResultInserter          2.702e-05    3.95484e-05    8.527e-05    3.48255e-05   1.58187e-05      10     
end_path:out1:RootOutput                            9.177e-06    1.19974e-05   2.5388e-05    1.05295e-05   4.55431e-06      10     
end_path:out1:RootOutput(write)                     0.961097       1.25496       1.63188       1.21064      0.178173        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6229.71 MB
  Peak resident set size usage (VmHWM): 4033.46 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 13366848
-rw-r--r-- 1 dune osgvo     230979 Dec 18 15:46 jobscript.log
-rw-r--r-- 1 dune osgvo        195 Dec 18 15:46 justin-processed-pfns.txt
-rw-r--r-- 1 dune osgvo        519 Dec 18 15:46 reco_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo       3574 Dec 18 15:46 Pandora_Geometry.xml
-rw-r--r-- 1 dune osgvo     139264 Dec 18 15:46 mem.db
-rw-r--r-- 1 dune osgvo      28672 Dec 18 15:46 time.db
-rw-r--r-- 1 dune osgvo 6928042688 Dec 18 15:46 pdhd_prod_beam__261612_108_1_20251212T160411Z_gen_g4_IonScintPDExt.root_262727_33_1_20251215T214335Z_PDInt.root_263305_8_1_20251218T200809Z_detsim_reco1.root
-rw-r--r-- 1 dune osgvo   38496940 Dec 18 15:46 Pandora_Events.pndr
-rw-r--r-- 1 dune osgvo          0 Dec 18 15:31 debugprod.log
-rw-r--r-- 1 dune osgvo        519 Dec 18 15:31 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 6720662392 Dec 18 15:31 temp_detsim.root
-rw-r--r-- 1 dune osgvo        122 Dec 18 14:08 all-input-dids.txt
justIN time: 2025-12-19 07:35:56 UTC       justIN version: 01.05.03