Jobsub ID 268917.14@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
0: frame: ident=189 time=0 tick=512 with 38786 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":18407 [0] "wiener0":20379 [20379] ] cmm:[ bad:0 lf_noisy:71 ] input 1: frame: ident=189 time=0 tick=512 with 34847 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":14649 [0] "wiener1":20198 [20198] ] cmm:[ bad:0 ] input 2: frame: ident=189 time=0 tick=512 with 36020 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":15366 [0] "wiener2":20654 [20654] ] cmm:[ bad:0 lf_noisy:1 ] input 3: frame: ident=189 time=0 tick=512 with 39792 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":17514 [0] "wiener3":22278 [22278] ] cmm:[ bad:0 lf_noisy:3 ] output: frame: ident=189 time=0 tick=512 with 149445 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":18407 [0] "gauss1":14649 [0] "gauss2":15366 [0] "gauss3":17514 [0] "wiener0":20379 [20379] "wiener1":20198 [20198] "wiener2":20654 [20654] "wiener3":22278 [22278] ] cmm:[ bad:0 lf_noisy:75 ]
Retagger: tagging trace set: wiener with 83509 traces, 83509 summary
Retagger: tagging trace set: gauss with 65936 traces, 0 summary
[21:55:05.178] D [ glue ] sink frame: #189 @0 with 149445 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[21:55:05.178] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[21:55:05.178] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[21:55:05.178] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[21:55:05.178] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[21:55:05.178] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[21:55:05.178] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[21:55:05.178] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[21:55:05.178] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[21:55:05.178] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[21:55:05.178] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[21:55:05.179] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[21:55:05.179] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[21:55:05.179] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[21:55:05.179] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[21:55:05.179] D [ glue ] frame sink sees EOS
[21:55:05.179] D [ pgraph ] <Pgrapher:> graph execution complete
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.23 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.67 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.66 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.64 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.04 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.04 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.03 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.02 sec
[21:55:05.179] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:55:05.179] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:55:05.179] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:55:05.179] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:55:05.179] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:55:05.179] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[21:55:05.179] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:55:05.179] I [ timer ] Timer: Total node execution : 33.3399992082268 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 65936 traces tagged "gauss"
FrameSaver: q=1.97105e+06 n=997890 tag=gauss
wclsFrameSaver: saving 83509 traces tagged "wiener"
FrameSaver: q=2.431e+06 n=974956 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 35931 XUs and 47182 XVs -> 1916 XUVs
C:0 T:3 19 XUs and 18 XVs -> 5 XUVs
C:0 T:4 4 XUs and 10 XVs -> 0 XUVs
C:0 T:5 12539 XUs and 13112 XVs -> 833 XUVs
C:0 T:6 28187 XUs and 43364 XVs -> 1740 XUVs
C:0 T:7 4 XUs and 12 XVs -> 0 XUVs
4494 XUVs total
1248 collection wire objects
4494 potential space points
Neighbour search...
467606 tests to find 245146 neighbours
Iterating with no regularization...
Begin: 2.47157e+07
0 2.34196e+07
1 2.32993e+07
2 2.32862e+07
Now with regularization...
Begin: 2.17047e+07
0 2.1701e+07
17-Dec-2025 21:55:23 UTC Closed output file "pdhd_prod_beam__267379_27_1_20251212T160808Z_gen_g4_IonScintPDExt.root_262726_117_1_20251215T212450Z_PDInt.root_268917_14_1_20251217T205312Z_detsim_reco1.root"
17-Dec-2025 21:55:23 UTC Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 51.176 60.0059 74.1895 56.9989 7.99684 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000558157 0.00272161 0.00403941 0.00286508 0.00123439 10
reco:wclsdatahd:WireCellToolkit 33.7211 34.0312 34.3417 34.0552 0.194022 10
reco:wirefilter:WireFilter 0.00902025 0.0103744 0.013541 0.0102521 0.0013016 10
reco:gaushit:GausHitFinder 0.2829 0.381855 0.558516 0.333876 0.0964481 10
reco:nhitsfilter:NumberOfHitsFilter 0.000127153 0.000176467 0.000345861 0.000152792 6.2764e-05 10
reco:reco3d:SpacePointSolver 0.127785 0.381686 1.01287 0.322525 0.252233 10
reco:hitpdune:DisambigFromSpacePoints 0.111558 0.296308 0.583975 0.227283 0.170515 10
reco:pandora:StandardPandora 2.63656 5.5755 12.4593 4.21921 2.98181 10
reco:pandoraWriter:StandardPandora 0.0884906 0.113691 0.154456 0.104912 0.0237241 10
reco:pandoraTrack:LArPandoraTrackCreation 0.254979 0.535631 0.914188 0.493415 0.210248 10
reco:pandoraShower:LArPandoraShowerCreation 0.281222 0.598447 1.05307 0.489196 0.274613 10
reco:pandoracalo:Calorimetry 0.156673 0.333099 0.530867 0.315914 0.110506 10
reco:pandoracalonosce:Calorimetry 0.148776 0.320969 0.508834 0.305584 0.107578 10
reco:pandoraShowercalo:ShowerCalorimetry 0.270633 0.623317 1.08631 0.524024 0.275383 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.264149 0.612552 1.07005 0.525131 0.273995 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0235502 0.0299506 0.0394578 0.0295036 0.00511754 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0197471 0.0290978 0.0379932 0.0295946 0.00561387 10
reco:emtrkmichelid:EmTrackMichelId 11.0536 15.322 20.2024 14.2517 3.35039 10
[art]:TriggerResults:TriggerResultInserter 1.7934e-05 2.99171e-05 0.000101624 2.1932e-05 2.39757e-05 10
end_path:out1:RootOutput 7.043e-06 1.10703e-05 2.8565e-05 9.6135e-06 5.92806e-06 10
end_path:out1:RootOutput(write) 0.61152 0.780548 1.01862 0.725776 0.144101 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5946.6 MB
Peak resident set size usage (VmHWM): 3777.25 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12324180
-rw-r--r-- 1 dunegli fnalgrid 231449 Dec 17 21:55 jobscript.log
-rw-r--r-- 1 dunegli fnalgrid 201 Dec 17 21:55 justin-processed-pfns.txt
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 17 21:55 reco_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 3574 Dec 17 21:55 Pandora_Geometry.xml
-rw-r--r-- 1 dunegli fnalgrid 139264 Dec 17 21:55 mem.db
-rw-r--r-- 1 dunegli fnalgrid 28672 Dec 17 21:55 time.db
-rw-r--r-- 1 dunegli fnalgrid 6388657123 Dec 17 21:55 pdhd_prod_beam__267379_27_1_20251212T160808Z_gen_g4_IonScintPDExt.root_262726_117_1_20251215T212450Z_PDInt.root_268917_14_1_20251217T205312Z_detsim_reco1.root
-rw-r--r-- 1 dunegli fnalgrid 36685020 Dec 17 21:55 Pandora_Events.pndr
-rw-r--r-- 1 dunegli fnalgrid 0 Dec 17 21:44 debugprod.log
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 17 21:44 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 6194158657 Dec 17 21:44 temp_detsim.root
-rw-r--r-- 1 dunegli fnalgrid 122 Dec 17 20:53 all-input-dids.txt
