justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 263305.9@dunegpschedd02.fnal.gov

Jobsub ID263305.9@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 22:09:07
From worker nodeHostnamemwt2-c196.campuscluster.illinois.edu
cpuinfoAMD EPYC 7402 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:00
Input filesusertests:pdhd_prod_beam__267378_17_1_20251212T160704Z_gen_g4_IonScintPDExt.root_268535_44_1_20251215T215119Z_PDInt.root
JobscriptExit code0
Real time1h (7127s)
CPU time1h (6860s = 96%)
Max RSS bytes11001126912 (10491 MiB)
Outputting started2025-12-18 22:06:47
Output filesusertests:pdhd_prod_beam__267378_17_1_20251212T160704Z_gen_g4_IonScintPDExt.root_268535_44_1_20251215T215119Z_PDInt.root_263305_9_1_20251218T200814Z_detsim_reco1.root
Finished2025-12-18 22:09:07
Saved logsjustin-logs:263305.9-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

lf_noisy:321 ] 
[16:06:02.413] D [  glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=179 time=0 tick=512 with 45773 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":21302 [0] "wiener0":24471 [24471] ] cmm:[ bad:0 lf_noisy:321 ] input 1: frame: ident=179 time=0 tick=512 with 41702 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":17985 [0] "wiener1":23717 [23717] ] cmm:[ bad:0 ] input 2: frame: ident=179 time=0 tick=512 with 42574 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":18179 [0] "wiener2":24395 [24395] ] cmm:[ bad:0 ] input 3: frame: ident=179 time=0 tick=512 with 48032 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":20380 [0] "wiener3":27652 [27652] ] cmm:[ bad:0 ] output: frame: ident=179 time=0 tick=512 with 178081 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":21302 [0] "gauss1":17985 [0] "gauss2":18179 [0] "gauss3":20380 [0] "wiener0":24471 [24471] "wiener1":23717 [23717] "wiener2":24395 [24395] "wiener3":27652 [27652] ] cmm:[ bad:0 lf_noisy:321 ] 
Retagger: tagging trace set: wiener with 100235 traces, 100235 summary
Retagger: tagging trace set: gauss with 77846 traces, 0 summary
[16:06:02.430] D [  glue  ] sink frame: #179 @0 with 178081 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[16:06:02.430] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[16:06:02.430] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[16:06:02.430] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[16:06:02.430] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[16:06:02.430] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[16:06:02.430] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[16:06:02.430] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[16:06:02.430] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[16:06:02.430] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[16:06:02.430] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[16:06:02.430] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[16:06:02.430] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[16:06:02.430] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[16:06:02.430] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[16:06:02.431] D [  glue  ] frame sink sees EOS
[16:06:02.431] D [ pgraph ] <Pgrapher:> graph execution complete 
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 13.03 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.52 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.43 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.2 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.32 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.84 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.74 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.49 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:06:02.431] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[16:06:02.431] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[16:06:02.431] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[16:06:02.431] I [ timer  ] Timer: Total node execution : 49.62000017054379 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 77846 traces tagged "gauss"
FrameSaver: q=2.40675e+06 n=1246969 tag=gauss
wclsFrameSaver: saving 100235 traces tagged "wiener"
FrameSaver: q=2.96201e+06 n=1168778 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 26503 XUs and 23437 XVs -> 1004 XUVs
C:0 T:3 9 XUs and 6 XVs -> 0 XUVs
C:0 T:4 9 XUs and 14 XVs -> 0 XUVs
C:0 T:5 36206 XUs and 24559 XVs -> 535 XUVs
C:0 T:6 32290 XUs and 30527 XVs -> 1012 XUVs
C:0 T:7 10 XUs and 30 XVs -> 0 XUVs
2551 XUVs total
1471 collection wire objects
2551 potential space points
Neighbour search...
49533 tests to find 28264 neighbours
Iterating with no regularization...
Begin: 1.23284e+07
0 1.11246e+07
1 1.10471e+07
2 1.10403e+07
Now with regularization...
Begin: 1.00227e+07
0 1.00208e+07
18-Dec-2025 16:06:46 CST  Closed output file "pdhd_prod_beam__267378_17_1_20251212T160704Z_gen_g4_IonScintPDExt.root_268535_44_1_20251215T215119Z_PDInt.root_263305_9_1_20251218T200814Z_detsim_reco1.root"
18-Dec-2025 16:06:46 CST  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           59.5427       93.629        115.742       95.4354       14.6063        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                              0.001244     0.00368517    0.00561968    0.00380784    0.00129638       10     
reco:wclsdatahd:WireCellToolkit                      51.0351       55.6617       62.7305       56.6999       3.30001        10     
reco:wirefilter:WireFilter                          0.0229571     0.0358099     0.0675991     0.0285627     0.0137744       10     
reco:gaushit:GausHitFinder                          0.407183      0.729912       1.35873      0.673164       0.23293        10     
reco:nhitsfilter:NumberOfHitsFilter                0.00023902    0.000448094   0.000590401   0.000433726   0.000107146      10     
reco:reco3d:SpacePointSolver                        0.201594      0.439088       0.69213      0.479962       0.14732         9     
reco:hitpdune:DisambigFromSpacePoints               0.141577       0.41781      0.700426      0.458746      0.162216         9     
reco:pandora:StandardPandora                         3.98378       8.42499       11.6804       8.20463       2.05894         9     
reco:pandoraWriter:StandardPandora                  0.174622      0.281152      0.329381      0.276938      0.0455639        9     
reco:pandoraTrack:LArPandoraTrackCreation           0.391167       1.03232       1.46769       1.0473       0.324639         9     
reco:pandoraShower:LArPandoraShowerCreation         0.344744       1.00667       1.96174       1.02488      0.417688         9     
reco:pandoracalo:Calorimetry                        0.265024       0.61278      0.850433      0.653272       0.18626         9     
reco:pandoracalonosce:Calorimetry                   0.251838      0.567427      0.729481      0.656786      0.165304         9     
reco:pandoraShowercalo:ShowerCalorimetry            0.303235       1.06871       2.01233       1.06631      0.439136         9     
reco:pandoraShowercalonosce:ShowerCalorimetry        0.28745      0.897519       1.67064      0.888347      0.348362         9     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0364135     0.0577619      0.07582      0.0593812     0.0146744        9     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0359794     0.0581948     0.0772473     0.0578553     0.0138313        9     
reco:emtrkmichelid:EmTrackMichelId                   16.057        24.8595       34.5453       24.7126       4.66938         9     
[art]:TriggerResults:TriggerResultInserter         3.3733e-05    5.14519e-05   0.000130606   3.79765e-05   2.89035e-05      10     
end_path:out1:RootOutput                            7.814e-06    1.20287e-05    3.729e-05     9.498e-06    8.45303e-06      10     
end_path:out1:RootOutput(write)                     0.936355       1.37138       1.69728       1.37107       0.19653        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6627.97 MB
  Peak resident set size usage (VmHWM): 4425.2 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 13015512
-rw-r--r-- 1 dune osgvo     231722 Dec 18 16:06 jobscript.log
-rw-r--r-- 1 dune osgvo        200 Dec 18 16:06 justin-processed-pfns.txt
-rw-r--r-- 1 dune osgvo        519 Dec 18 16:06 reco_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo       3574 Dec 18 16:06 Pandora_Geometry.xml
-rw-r--r-- 1 dune osgvo     135168 Dec 18 16:06 mem.db
-rw-r--r-- 1 dune osgvo      28672 Dec 18 16:06 time.db
-rw-r--r-- 1 dune osgvo 6742073983 Dec 18 16:06 pdhd_prod_beam__267378_17_1_20251212T160704Z_gen_g4_IonScintPDExt.root_268535_44_1_20251215T215119Z_PDInt.root_263305_9_1_20251218T200814Z_detsim_reco1.root
-rw-r--r-- 1 dune osgvo   30514535 Dec 18 16:06 Pandora_Events.pndr
-rw-r--r-- 1 dune osgvo          0 Dec 18 15:48 debugprod.log
-rw-r--r-- 1 dune osgvo        519 Dec 18 15:48 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 6554846152 Dec 18 15:48 temp_detsim.root
-rw-r--r-- 1 dune osgvo        121 Dec 18 14:08 all-input-dids.txt
justIN time: 2025-12-19 07:45:11 UTC       justIN version: 01.05.03