Jobsub ID 268896.45@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ame: ident=189 time=0 tick=512 with 64817 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":30187 [0] "wiener0":34630 [34630] ] cmm:[ bad:0 lf_noisy:156 ] input 1: frame: ident=189 time=0 tick=512 with 37269 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":15961 [0] "wiener1":21308 [21308] ] cmm:[ bad:0 ] input 2: frame: ident=189 time=0 tick=512 with 36874 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":16007 [0] "wiener2":20867 [20867] ] cmm:[ bad:0 ] input 3: frame: ident=189 time=0 tick=512 with 56242 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":23398 [0] "wiener3":32844 [32844] ] cmm:[ bad:0 ] output: frame: ident=189 time=0 tick=512 with 195202 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":30187 [0] "gauss1":15961 [0] "gauss2":16007 [0] "gauss3":23398 [0] "wiener0":34630 [34630] "wiener1":21308 [21308] "wiener2":20867 [20867] "wiener3":32844 [32844] ] cmm:[ bad:0 lf_noisy:156 ]
Retagger: tagging trace set: wiener with 109649 traces, 109649 summary
Retagger: tagging trace set: gauss with 85553 traces, 0 summary
[20:47:29.725] D [ glue ] sink frame: #189 @0 with 195202 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[20:47:29.725] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[20:47:29.725] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[20:47:29.725] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[20:47:29.725] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[20:47:29.725] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[20:47:29.725] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[20:47:29.725] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[20:47:29.725] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[20:47:29.725] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[20:47:29.725] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[20:47:29.725] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[20:47:29.725] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[20:47:29.725] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[20:47:29.725] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[20:47:29.725] D [ glue ] frame sink sees EOS
[20:47:29.725] D [ pgraph ] <Pgrapher:> graph execution complete
[20:47:29.725] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.3 sec
[20:47:29.725] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.97 sec
[20:47:29.725] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.76 sec
[20:47:29.725] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.74 sec
[20:47:29.725] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.78 sec
[20:47:29.725] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.78 sec
[20:47:29.725] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.75 sec
[20:47:29.725] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.73 sec
[20:47:29.726] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[20:47:29.726] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[20:47:29.726] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[20:47:29.726] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:29.726] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[20:47:29.726] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:29.726] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[20:47:29.726] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[20:47:29.726] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[20:47:29.726] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[20:47:29.726] I [ timer ] Timer: Total node execution : 45.84999994188547 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 85553 traces tagged "gauss"
FrameSaver: q=2.73299e+06 n=1393656 tag=gauss
wclsFrameSaver: saving 109649 traces tagged "wiener"
FrameSaver: q=3.41857e+06 n=1326320 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 20043 XUs and 52045 XVs -> 4376 XUVs
C:0 T:3 34 XUs and 47 XVs -> 8 XUVs
C:0 T:4 9 XUs and 6 XVs -> 0 XUVs
C:0 T:5 21588 XUs and 14082 XVs -> 426 XUVs
C:0 T:6 31582 XUs and 54706 XVs -> 1315 XUVs
C:0 T:7 4 XUs and 13 XVs -> 0 XUVs
6125 XUVs total
2010 collection wire objects
6125 potential space points
Neighbour search...
817347 tests to find 419298 neighbours
Iterating with no regularization...
Begin: 1.6192e+07
0 1.45968e+07
1 1.44701e+07
2 1.44412e+07
3 1.44321e+07
Now with regularization...
Begin: 1.33279e+07
0 1.33201e+07
17-Dec-2025 20:48:17 UTC Closed output file "pdhd_prod_beam__267379_84_1_20251212T161850Z_gen_g4_IonScintPDExt.root_262726_59_1_20251215T212410Z_PDInt.root_268896_45_1_20251217T183307Z_detsim_reco1.root"
17-Dec-2025 20:48:17 UTC Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 78.0853 95.5501 111.746 97.6601 12.7347 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00163929 0.00526792 0.0219077 0.00373614 0.00566102 10
reco:wclsdatahd:WireCellToolkit 45.1188 48.6486 55.0408 48.4395 2.80261 10
reco:wirefilter:WireFilter 0.0133835 0.0181164 0.0260573 0.0180977 0.00380929 10
reco:gaushit:GausHitFinder 0.423792 0.656149 0.926537 0.674149 0.157642 10
reco:nhitsfilter:NumberOfHitsFilter 0.000178931 0.000323398 0.000584143 0.000313707 0.000111984 10
reco:reco3d:SpacePointSolver 0.224701 0.636401 1.2853 0.423666 0.42158 10
reco:hitpdune:DisambigFromSpacePoints 0.196544 0.451136 0.837931 0.44708 0.207212 10
reco:pandora:StandardPandora 3.99409 7.76055 11.5437 7.50074 2.78841 10
reco:pandoraWriter:StandardPandora 0.123661 0.431044 2.63599 0.196003 0.736191 10
reco:pandoraTrack:LArPandoraTrackCreation 0.418824 0.883385 1.48469 0.865286 0.341718 10
reco:pandoraShower:LArPandoraShowerCreation 0.455586 0.997885 1.51897 1.01871 0.383354 10
reco:pandoracalo:Calorimetry 0.268379 0.451957 0.665304 0.456798 0.139633 10
reco:pandoracalonosce:Calorimetry 0.260555 0.441388 0.687322 0.438994 0.141856 10
reco:pandoraShowercalo:ShowerCalorimetry 0.440724 0.960067 1.63372 0.948441 0.39676 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.426839 0.98425 1.66719 1.02513 0.403227 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0300381 0.0521314 0.0746843 0.0533536 0.0132916 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.029973 0.0509263 0.072472 0.0527262 0.0122593 10
reco:emtrkmichelid:EmTrackMichelId 19.8533 30.7747 40.4278 32.4057 6.80634 10
[art]:TriggerResults:TriggerResultInserter 1.882e-05 2.85351e-05 7.176e-05 2.407e-05 1.46839e-05 10
end_path:out1:RootOutput 8.02e-06 1.06292e-05 2.6451e-05 9.045e-06 5.3102e-06 10
end_path:out1:RootOutput(write) 0.882758 1.29646 1.59741 1.34241 0.235795 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6105.06 MB
Peak resident set size usage (VmHWM): 3909.48 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12341976
-rw-r--r-- 1 dunegli fnalgrid 231428 Dec 17 20:48 jobscript.log
-rw-r--r-- 1 dunegli fnalgrid 200 Dec 17 20:48 justin-processed-pfns.txt
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 17 20:48 reco_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 3574 Dec 17 20:48 Pandora_Geometry.xml
-rw-r--r-- 1 dunegli fnalgrid 139264 Dec 17 20:48 mem.db
-rw-r--r-- 1 dunegli fnalgrid 28672 Dec 17 20:48 time.db
-rw-r--r-- 1 dunegli fnalgrid 6402071268 Dec 17 20:48 pdhd_prod_beam__267379_84_1_20251212T161850Z_gen_g4_IonScintPDExt.root_262726_59_1_20251215T212410Z_PDInt.root_268896_45_1_20251217T183307Z_detsim_reco1.root
-rw-r--r-- 1 dunegli fnalgrid 37781720 Dec 17 20:47 Pandora_Events.pndr
-rw-r--r-- 1 dunegli fnalgrid 0 Dec 17 20:16 debugprod.log
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 17 20:15 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 6197876861 Dec 17 20:15 temp_detsim.root
-rw-r--r-- 1 dunegli fnalgrid 121 Dec 17 18:33 all-input-dids.txt
