Jobsub ID 269175.0@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
0 tick=512 with 57276 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":26800 [0] "wiener0":30476 [30476] ] cmm:[ bad:0 lf_noisy:33 ] input 1: frame: ident=199 time=0 tick=512 with 49865 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":20651 [0] "wiener1":29214 [29214] ] cmm:[ bad:0 lf_noisy:1 ] input 2: frame: ident=199 time=0 tick=512 with 42672 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":17754 [0] "wiener2":24918 [24918] ] cmm:[ bad:0 lf_noisy:2 ] input 3: frame: ident=199 time=0 tick=512 with 37645 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":16059 [0] "wiener3":21586 [21586] ] cmm:[ bad:0 ] output: frame: ident=199 time=0 tick=512 with 187458 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":26800 [0] "gauss1":20651 [0] "gauss2":17754 [0] "gauss3":16059 [0] "wiener0":30476 [30476] "wiener1":29214 [29214] "wiener2":24918 [24918] "wiener3":21586 [21586] ] cmm:[ bad:0 lf_noisy:36 ]
Retagger: tagging trace set: wiener with 106194 traces, 106194 summary
Retagger: tagging trace set: gauss with 81264 traces, 0 summary
[21:43:07.328] D [ glue ] sink frame: #199 @0 with 187458 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[21:43:07.329] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[21:43:07.329] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[21:43:07.329] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[21:43:07.329] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[21:43:07.329] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[21:43:07.329] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[21:43:07.329] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[21:43:07.329] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[21:43:07.329] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[21:43:07.329] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[21:43:07.329] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[21:43:07.329] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[21:43:07.329] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[21:43:07.329] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[21:43:07.329] D [ glue ] frame sink sees EOS
[21:43:07.329] D [ pgraph ] <Pgrapher:> graph execution complete
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.72 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.25 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.72 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.88 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 5.63 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 4.55 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 4.34 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.88 sec
[21:43:07.329] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[21:43:07.329] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[21:43:07.329] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:43:07.329] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:43:07.329] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:43:07.329] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:43:07.329] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:43:07.329] I [ timer ] Timer: Total node execution : 78.05000026524067 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 81264 traces tagged "gauss"
FrameSaver: q=2.74006e+06 n=1267183 tag=gauss
wclsFrameSaver: saving 106194 traces tagged "wiener"
FrameSaver: q=3.48674e+06 n=1217331 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 26311 XUs and 42960 XVs -> 1641 XUVs
C:0 T:3 18 XUs and 26 XVs -> 3 XUVs
C:0 T:4 9 XUs and 5 XVs -> 0 XUVs
C:0 T:5 16033 XUs and 25924 XVs -> 738 XUVs
C:0 T:6 15847 XUs and 21989 XVs -> 869 XUVs
C:0 T:7 6 XUs and 13 XVs -> 0 XUVs
3251 XUVs total
2013 collection wire objects
3251 potential space points
Neighbour search...
47351 tests to find 29260 neighbours
Iterating with no regularization...
Begin: 2.75668e+07
0 2.6562e+07
1 2.65213e+07
2 2.6518e+07
Now with regularization...
Begin: 2.4246e+07
0 2.42449e+07
18-Dec-2025 21:43:54 EST Closed output file "pdhd_prod_beam__261613_77_1_20251212T161409Z_gen_g4_IonScintPDExt.root_262728_130_1_20251215T220545Z_PDInt.root_269175_0_1_20251219T005919Z_detsim_reco1.root"
18-Dec-2025 21:43:54 EST Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 108.365 124.382 140.198 124.673 9.44844 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00139413 0.00659171 0.0141734 0.00575955 0.00391771 10
reco:wclsdatahd:WireCellToolkit 80.4564 85.9232 91.8218 85.9226 3.72569 10
reco:wirefilter:WireFilter 0.0256952 0.0354281 0.0412872 0.0358377 0.0046395 10
reco:gaushit:GausHitFinder 0.680842 1.04113 1.38436 1.06387 0.206307 10
reco:nhitsfilter:NumberOfHitsFilter 0.000283156 0.000416626 0.000706613 0.000409329 0.000112147 10
reco:reco3d:SpacePointSolver 0.274714 0.617539 1.43009 0.581328 0.30159 10
reco:hitpdune:DisambigFromSpacePoints 0.326051 0.708796 0.994787 0.734407 0.207099 10
reco:pandora:StandardPandora 7.20253 11.2983 17.7669 10.9554 2.83664 10
reco:pandoraWriter:StandardPandora 0.190981 0.298943 0.374395 0.311438 0.0498165 10
reco:pandoraTrack:LArPandoraTrackCreation 0.657649 1.61258 2.43509 1.68963 0.476613 10
reco:pandoraShower:LArPandoraShowerCreation 0.658682 1.43702 1.9337 1.51288 0.384614 10
reco:pandoracalo:Calorimetry 0.36878 0.831342 1.11359 0.902512 0.201651 10
reco:pandoracalonosce:Calorimetry 0.35545 0.804825 1.15659 0.832814 0.220256 10
reco:pandoraShowercalo:ShowerCalorimetry 0.603219 1.37759 2.03834 1.45729 0.442272 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.599212 1.35657 1.97961 1.45628 0.425652 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0568374 0.090407 0.12387 0.0889985 0.0201945 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0574717 0.0906239 0.117706 0.0893275 0.0182357 10
reco:emtrkmichelid:EmTrackMichelId 10.9346 14.9764 17.6343 15.508 2.2259 10
[art]:TriggerResults:TriggerResultInserter 4.2602e-05 5.91034e-05 0.000128517 5.0578e-05 2.42703e-05 10
end_path:out1:RootOutput 4.693e-06 1.13697e-05 3.497e-05 9.434e-06 8.0156e-06 10
end_path:out1:RootOutput(write) 1.28119 1.81346 2.27369 1.85992 0.239972 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6149.32 MB
Peak resident set size usage (VmHWM): 3787.28 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 11735356
-rw-r--r-- 1 fermipro fermilab 231343 Dec 18 21:43 jobscript.log
-rw-r--r-- 1 fermipro fermilab 201 Dec 18 21:43 justin-processed-pfns.txt
-rw-r--r-- 1 fermipro fermilab 519 Dec 18 21:43 reco_protodunehd_hist.root
-rw-r--r-- 1 fermipro fermilab 3574 Dec 18 21:43 Pandora_Geometry.xml
-rw-r--r-- 1 fermipro fermilab 139264 Dec 18 21:43 mem.db
-rw-r--r-- 1 fermipro fermilab 28672 Dec 18 21:43 time.db
-rw-r--r-- 1 fermipro fermilab 6079920730 Dec 18 21:43 pdhd_prod_beam__261613_77_1_20251212T161409Z_gen_g4_IonScintPDExt.root_262728_130_1_20251215T220545Z_PDInt.root_269175_0_1_20251219T005919Z_detsim_reco1.root
-rw-r--r-- 1 fermipro fermilab 31209220 Dec 18 21:43 Pandora_Events.pndr
-rw-r--r-- 1 fermipro fermilab 0 Dec 18 21:20 debugprod.log
-rw-r--r-- 1 fermipro fermilab 519 Dec 18 21:20 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 fermipro fermilab 5905432164 Dec 18 21:20 temp_detsim.root
-rw-r--r-- 1 fermipro fermilab 122 Dec 18 19:59 all-input-dids.txt
