Jobsub ID 268917.5@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ident=189 time=0 tick=512 with 51226 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":24087 [0] "wiener0":27139 [27139] ] cmm:[ bad:0 lf_noisy:302 ] input 1: frame: ident=189 time=0 tick=512 with 43803 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":18764 [0] "wiener1":25039 [25039] ] cmm:[ bad:0 ] input 2: frame: ident=189 time=0 tick=512 with 59204 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":23751 [0] "wiener2":35453 [35453] ] cmm:[ bad:0 ] input 3: frame: ident=189 time=0 tick=512 with 45272 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":19178 [0] "wiener3":26094 [26094] ] cmm:[ bad:0 ] output: frame: ident=189 time=0 tick=512 with 199505 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":24087 [0] "gauss1":18764 [0] "gauss2":23751 [0] "gauss3":19178 [0] "wiener0":27139 [27139] "wiener1":25039 [25039] "wiener2":35453 [35453] "wiener3":26094 [26094] ] cmm:[ bad:0 lf_noisy:302 ]
Retagger: tagging trace set: wiener with 113725 traces, 113725 summary
Retagger: tagging trace set: gauss with 85780 traces, 0 summary
[21:50:46.728] D [ glue ] sink frame: #189 @0 with 199505 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[21:50:46.728] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[21:50:46.728] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[21:50:46.728] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[21:50:46.728] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[21:50:46.728] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[21:50:46.728] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[21:50:46.728] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[21:50:46.728] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[21:50:46.728] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[21:50:46.728] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[21:50:46.728] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[21:50:46.728] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[21:50:46.728] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[21:50:46.728] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[21:50:46.728] D [ glue ] frame sink sees EOS
[21:50:46.728] D [ pgraph ] <Pgrapher:> graph execution complete
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.49 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.31 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.13 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.06 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.91 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.88 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.88 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.87 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:50:46.728] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:50:46.728] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[21:50:46.728] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:50:46.728] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:50:46.728] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[21:50:46.728] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:50:46.728] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:50:46.728] I [ timer ] Timer: Total node execution : 30.549999732524157 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 85780 traces tagged "gauss"
FrameSaver: q=2.75865e+06 n=1355392 tag=gauss
wclsFrameSaver: saving 113725 traces tagged "wiener"
FrameSaver: q=3.46972e+06 n=1307612 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 62642 XUs and 64583 XVs -> 2455 XUVs
C:0 T:3 205 XUs and 106 XVs -> 4 XUVs
C:0 T:4 10 XUs and 16 XVs -> 1 XUVs
C:0 T:5 52410 XUs and 54805 XVs -> 6055 XUVs
C:0 T:6 61223 XUs and 59099 XVs -> 871 XUVs
C:0 T:7 44 XUs and 10 XVs -> 0 XUVs
9386 XUVs total
2331 collection wire objects
9386 potential space points
Neighbour search...
856426 tests to find 510910 neighbours
Iterating with no regularization...
Begin: 2.28131e+07
0 2.00546e+07
1 1.98425e+07
2 1.97979e+07
3 1.97797e+07
Now with regularization...
Begin: 1.8004e+07
0 1.79881e+07
17-Dec-2025 21:51:15 UTC Closed output file "pdhd_prod_beam__267379_116_1_20251212T162200Z_gen_g4_IonScintPDExt.root_262726_151_1_20251215T213833Z_PDInt.root_268917_5_1_20251217T205309Z_detsim_reco1.root"
17-Dec-2025 21:51:15 UTC Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 45.4832 55.6503 75.2743 54.4684 7.93558 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000383736 0.00233967 0.00384124 0.00227012 0.00108489 10
reco:wclsdatahd:WireCellToolkit 31.0403 31.4323 31.9164 31.4169 0.265642 10
reco:wirefilter:WireFilter 0.00789658 0.00965354 0.0126837 0.00921921 0.00133961 10
reco:gaushit:GausHitFinder 0.257774 0.361586 0.545294 0.366045 0.0809511 10
reco:nhitsfilter:NumberOfHitsFilter 9.9671e-05 0.000168977 0.000410878 0.000136877 8.61624e-05 10
reco:reco3d:SpacePointSolver 0.172252 0.320427 0.805719 0.220765 0.208167 10
reco:hitpdune:DisambigFromSpacePoints 0.151121 0.266351 0.552188 0.230665 0.116482 10
reco:pandora:StandardPandora 1.67224 4.96163 12.5221 4.48115 2.81969 10
reco:pandoraWriter:StandardPandora 0.063609 0.0966458 0.140912 0.0926072 0.0229403 10
reco:pandoraTrack:LArPandoraTrackCreation 0.208751 0.451061 0.681105 0.441101 0.13386 10
reco:pandoraShower:LArPandoraShowerCreation 0.21315 0.506847 1.13215 0.464662 0.235642 10
reco:pandoracalo:Calorimetry 0.136404 0.285212 0.476536 0.268703 0.0870101 10
reco:pandoracalonosce:Calorimetry 0.132762 0.275441 0.462807 0.25954 0.0847049 10
reco:pandoraShowercalo:ShowerCalorimetry 0.20009 0.560934 1.35994 0.516699 0.293345 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.193033 0.551542 1.33925 0.507907 0.289412 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0193248 0.0271582 0.0395248 0.0260926 0.00575187 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0191666 0.026362 0.0382315 0.0248747 0.00544408 10
reco:emtrkmichelid:EmTrackMichelId 9.69044 14.4098 22.0032 13.9989 3.38294 10
[art]:TriggerResults:TriggerResultInserter 1.3797e-05 2.57975e-05 0.000106023 1.69725e-05 2.68667e-05 10
end_path:out1:RootOutput 4.027e-06 9.432e-06 4.0748e-05 4.7445e-06 1.07242e-05 10
end_path:out1:RootOutput(write) 0.599773 1.08714 2.53262 0.904021 0.548835 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6053.45 MB
Peak resident set size usage (VmHWM): 3893.77 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12865508
-rw-r--r-- 1 dunegli fnalgrid 231322 Dec 17 21:51 jobscript.log
-rw-r--r-- 1 dunegli fnalgrid 202 Dec 17 21:51 justin-processed-pfns.txt
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 17 21:51 reco_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 3574 Dec 17 21:51 Pandora_Geometry.xml
-rw-r--r-- 1 dunegli fnalgrid 139264 Dec 17 21:51 mem.db
-rw-r--r-- 1 dunegli fnalgrid 28672 Dec 17 21:51 time.db
-rw-r--r-- 1 dunegli fnalgrid 6667025400 Dec 17 21:51 pdhd_prod_beam__267379_116_1_20251212T162200Z_gen_g4_IonScintPDExt.root_262726_151_1_20251215T213833Z_PDInt.root_268917_5_1_20251217T205309Z_detsim_reco1.root
-rw-r--r-- 1 dunegli fnalgrid 36724290 Dec 17 21:50 Pandora_Events.pndr
-rw-r--r-- 1 dunegli fnalgrid 0 Dec 17 21:40 debugprod.log
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 17 21:40 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 6470010864 Dec 17 21:40 temp_detsim.root
-rw-r--r-- 1 dunegli fnalgrid 123 Dec 17 20:53 all-input-dids.txt
