justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 269119.38@dunegpschedd01.fnal.gov

Jobsub ID269119.38@dunegpschedd01.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:29:50
SiteUS_UChicago
EntryEngage_US_MWT2_uiuc_condce_mcore
Last heartbeat2025-12-18 22:22:03
From worker nodeHostnamemwt2-c164.campuscluster.illinois.edu
cpuinfoIntel(R) Xeon(R) Gold 6148 CPU @ 2.40GHz
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 19:50:37
Input filesusertests:pdhd_prod_beam__261612_4_1_20251212T155442Z_gen_g4_IonScintPDExt.root_262727_39_1_20251215T214525Z_PDInt.root
JobscriptExit code0
Real time2h (8955s)
CPU time2h (8676s = 96%)
Max RSS bytes7243681792 (6908 MiB)
Outputting started2025-12-18 22:19:53
Output filesusertests:pdhd_prod_beam__261612_4_1_20251212T155442Z_gen_g4_IonScintPDExt.root_262727_39_1_20251215T214525Z_PDInt.root_269119_38_1_20251218T195048Z_detsim_reco1.root
Finished2025-12-18 22:22:03
Saved logsjustin-logs:269119.38-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

iener0":21395 [21395] ] cmm:[ bad:0 lf_noisy:146 ] 
[16:18:27.804] D [  glue  ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=179 time=0 tick=512 with 41394 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":19999 [0] "wiener0":21395 [21395] ] cmm:[ bad:0 lf_noisy:146 ] input 1: frame: ident=179 time=0 tick=512 with 42657 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":18744 [0] "wiener1":23913 [23913] ] cmm:[ bad:0 ] input 2: frame: ident=179 time=0 tick=512 with 35326 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":16202 [0] "wiener2":19124 [19124] ] cmm:[ bad:0 ] input 3: frame: ident=179 time=0 tick=512 with 43050 traces.  frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":19106 [0] "wiener3":23944 [23944] ] cmm:[ bad:0 ] output: frame: ident=179 time=0 tick=512 with 162427 traces.  frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":19999 [0] "gauss1":18744 [0] "gauss2":16202 [0] "gauss3":19106 [0] "wiener0":21395 [21395] "wiener1":23913 [23913] "wiener2":19124 [19124] "wiener3":23944 [23944] ] cmm:[ bad:0 lf_noisy:146 ] 
Retagger: tagging trace set: wiener with 88376 traces, 88376 summary
Retagger: tagging trace set: gauss with 74051 traces, 0 summary
[16:18:27.828] D [  glue  ] sink frame: #179 @0 with 162427 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[16:18:27.828] D [  glue  ] <FrameFanout:sn_mag_nf> call=19: see EOS 
[16:18:27.828] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=9 
[16:18:27.828] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19 
[16:18:27.828] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3 
[16:18:27.828] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=9 
[16:18:27.828] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19 
[16:18:27.828] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2 
[16:18:27.828] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=9 
[16:18:27.829] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19 
[16:18:27.829] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1 
[16:18:27.829] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=9 
[16:18:27.829] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19 
[16:18:27.829] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0 
[16:18:27.829] D [  glue  ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4 
[16:18:27.829] D [  glue  ] frame sink sees EOS
[16:18:27.829] D [ pgraph ] <Pgrapher:> graph execution complete 
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 21.86 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 15.8 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 15.12 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 14.52 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 4.05 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 4.02 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.93 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.92 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.02 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:18:27.829] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[16:18:27.829] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[16:18:27.829] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[16:18:27.829] I [ timer  ] Timer: Total node execution : 83.30000145733356 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 74051 traces tagged "gauss"
FrameSaver: q=2.34361e+06 n=1229080 tag=gauss
wclsFrameSaver: saving 88376 traces tagged "wiener"
FrameSaver: q=2.75937e+06 n=1186701 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 26840 XUs and 27931 XVs -> 1351 XUVs
C:0 T:3 62 XUs and 81 XVs -> 1 XUVs
C:0 T:5 13563 XUs and 10621 XVs -> 539 XUVs
C:0 T:6 30384 XUs and 28030 XVs -> 866 XUVs
C:0 T:7 3 XUs and 4 XVs -> 0 XUVs
2757 XUVs total
1638 collection wire objects
2757 potential space points
Neighbour search...
66733 tests to find 34968 neighbours
Iterating with no regularization...
Begin: 4.56096e+07
0 4.35961e+07
1 4.34735e+07
2 4.34618e+07
Now with regularization...
Begin: 4.08459e+07
0 4.08404e+07
18-Dec-2025 16:19:51 CST  Closed output file "pdhd_prod_beam__261612_4_1_20251212T155442Z_gen_g4_IonScintPDExt.root_262727_39_1_20251215T214525Z_PDInt.root_269119_38_1_20251218T195048Z_detsim_reco1.root"
18-Dec-2025 16:19:51 CST  Closed input file "temp_detsim.root"

=====================================================================================================================================
TimeTracker printout (sec)                             Min           Avg           Max         Median          RMS         nEvts   
=====================================================================================================================================
Full event                                           118.528       154.831       173.908       158.954       16.8462        10     
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                             0.00145615    0.00445388     0.0072473    0.00462808    0.00165522       10     
reco:wclsdatahd:WireCellToolkit                      76.8004       85.7111       96.9411       85.2146       6.55295        10     
reco:wirefilter:WireFilter                          0.0301765     0.0475016     0.0631424     0.0466043    0.00839703       10     
reco:gaushit:GausHitFinder                          0.526491      0.982041       1.21703       1.0318       0.208182        10     
reco:nhitsfilter:NumberOfHitsFilter                0.000366936   0.000541288   0.000930072   0.000520195   0.00015011       10     
reco:reco3d:SpacePointSolver                        0.346203      0.796833       1.17483      0.802872       0.24203        10     
reco:hitpdune:DisambigFromSpacePoints                0.3732       0.836479       1.19853      0.871346      0.246977        10     
reco:pandora:StandardPandora                         8.69128       15.3895       23.5194       15.9017       4.36499        10     
reco:pandoraWriter:StandardPandora                  0.274605      0.367741      0.504843      0.360721      0.0670987       10     
reco:pandoraTrack:LArPandoraTrackCreation            1.57535       2.59609       3.09492       2.7708       0.486968        10     
reco:pandoraShower:LArPandoraShowerCreation          1.1362        2.84898       3.84162       3.34557      0.974332        10     
reco:pandoracalo:Calorimetry                         0.8553        1.34681       1.79393       1.39631      0.275609        10     
reco:pandoracalonosce:Calorimetry                   0.746944       1.22452       1.51771       1.29208      0.261848        10     
reco:pandoraShowercalo:ShowerCalorimetry            0.894872       2.51261       3.49716       2.82315      0.871164        10     
reco:pandoraShowercalonosce:ShowerCalorimetry       0.932563       2.4704        3.54563       2.7782       0.908721        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry          0.0521138      0.10525      0.164542      0.099192      0.0296503       10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0798363     0.0997774     0.120913      0.097012      0.0137406       10     
reco:emtrkmichelid:EmTrackMichelId                   21.3491       35.4589       46.8682       37.214        7.36131        10     
[art]:TriggerResults:TriggerResultInserter         4.1012e-05    7.30257e-05   0.000106638   7.3013e-05    1.97906e-05      10     
end_path:out1:RootOutput                           1.0008e-05    1.57351e-05   3.8503e-05    1.3044e-05    7.87754e-06      10     
end_path:out1:RootOutput(write)                      1.36526       1.94594       2.67044       1.88006       0.36515        10     
=====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6139.79 MB
  Peak resident set size usage (VmHWM): 3951.39 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12075412
-rw-r--r-- 1 dune osgvo     231135 Dec 18 16:19 jobscript.log
-rw-r--r-- 1 dune osgvo        199 Dec 18 16:19 justin-processed-pfns.txt
-rw-r--r-- 1 dune osgvo        519 Dec 18 16:19 reco_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo       3574 Dec 18 16:19 Pandora_Geometry.xml
-rw-r--r-- 1 dune osgvo     139264 Dec 18 16:19 mem.db
-rw-r--r-- 1 dune osgvo      28672 Dec 18 16:19 time.db
-rw-r--r-- 1 dune osgvo 6262731254 Dec 18 16:19 pdhd_prod_beam__261612_4_1_20251212T155442Z_gen_g4_IonScintPDExt.root_262727_39_1_20251215T214525Z_PDInt.root_269119_38_1_20251218T195048Z_detsim_reco1.root
-rw-r--r-- 1 dune osgvo   37183870 Dec 18 16:18 Pandora_Events.pndr
-rw-r--r-- 1 dune osgvo          0 Dec 18 15:51 debugprod.log
-rw-r--r-- 1 dune osgvo        519 Dec 18 15:50 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 6064856018 Dec 18 15:50 temp_detsim.root
-rw-r--r-- 1 dune osgvo        120 Dec 18 13:50 all-input-dids.txt
justIN time: 2025-12-19 13:26:13 UTC       justIN version: 01.05.03