Jobsub ID 263305.25@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
9 time=0 tick=512 with 52947 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":24781 [0] "wiener0":28166 [28166] ] cmm:[ bad:0 lf_noisy:45 ] input 1: frame: ident=179 time=0 tick=512 with 61751 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":26248 [0] "wiener1":35503 [35503] ] cmm:[ bad:0 lf_noisy:2 ] input 2: frame: ident=179 time=0 tick=512 with 53328 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":22250 [0] "wiener2":31078 [31078] ] cmm:[ bad:0 lf_noisy:1 ] input 3: frame: ident=179 time=0 tick=512 with 62626 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":26570 [0] "wiener3":36056 [36056] ] cmm:[ bad:0 ] output: frame: ident=179 time=0 tick=512 with 230652 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":24781 [0] "gauss1":26248 [0] "gauss2":22250 [0] "gauss3":26570 [0] "wiener0":28166 [28166] "wiener1":35503 [35503] "wiener2":31078 [31078] "wiener3":36056 [36056] ] cmm:[ bad:0 lf_noisy:48 ]
Retagger: tagging trace set: wiener with 130803 traces, 130803 summary
Retagger: tagging trace set: gauss with 99849 traces, 0 summary
[21:56:23.769] D [ glue ] sink frame: #179 @0 with 230652 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[21:56:23.769] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[21:56:23.769] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[21:56:23.769] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[21:56:23.769] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[21:56:23.769] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[21:56:23.769] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[21:56:23.769] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[21:56:23.769] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[21:56:23.769] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[21:56:23.769] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[21:56:23.769] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[21:56:23.769] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[21:56:23.769] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[21:56:23.769] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[21:56:23.769] D [ glue ] frame sink sees EOS
[21:56:23.769] D [ pgraph ] <Pgrapher:> graph execution complete
[21:56:23.769] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.05 sec
[21:56:23.769] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.69 sec
[21:56:23.769] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.53 sec
[21:56:23.769] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.53 sec
[21:56:23.769] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.95 sec
[21:56:23.769] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.93 sec
[21:56:23.770] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.93 sec
[21:56:23.770] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 1.93 sec
[21:56:23.770] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[21:56:23.770] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[21:56:23.770] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:23.770] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[21:56:23.770] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:23.770] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:23.770] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:23.770] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:56:23.770] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:56:23.770] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:56:23.770] I [ timer ] Timer: Total node execution : 32.56000055745244 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 99849 traces tagged "gauss"
FrameSaver: q=3.4037e+06 n=1671500 tag=gauss
wclsFrameSaver: saving 130803 traces tagged "wiener"
FrameSaver: q=4.12184e+06 n=1553772 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 112515 XUs and 65443 XVs -> 1892 XUVs
C:0 T:5 46213 XUs and 45918 XVs -> 1572 XUVs
C:0 T:6 59669 XUs and 53901 XVs -> 1359 XUVs
C:0 T:7 10 XUs and 9 XVs -> 0 XUVs
4823 XUVs total
2733 collection wire objects
4823 potential space points
Neighbour search...
103267 tests to find 59504 neighbours
Iterating with no regularization...
Begin: 8.04305e+07
0 7.38925e+07
1 7.34881e+07
2 7.34571e+07
Now with regularization...
Begin: 7.02119e+07
0 7.02027e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Dec-2025 21:57:11 UTC Closed output file "pdhd_prod_beam__261612_52_1_20251212T155737Z_gen_g4_IonScintPDExt.root_268535_34_1_20251215T215017Z_PDInt.root_263305_25_1_20251218T205248Z_detsim_reco1.root"
18-Dec-2025 21:57:11 UTC Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 50.6975 59.6603 80.1127 56.9765 8.46242 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000381862 0.00246948 0.00390575 0.00252454 0.00105457 10
reco:wclsdatahd:WireCellToolkit 31.7746 32.5922 33.3663 32.6689 0.442873 10
reco:wirefilter:WireFilter 0.00889982 0.0104722 0.014291 0.0101881 0.00146946 10
reco:gaushit:GausHitFinder 0.28672 0.403277 0.570698 0.383908 0.0858055 10
reco:nhitsfilter:NumberOfHitsFilter 0.000131612 0.000196949 0.000413964 0.0001669 7.96445e-05 10
reco:reco3d:SpacePointSolver 0.141955 0.415837 0.769232 0.358497 0.183679 10
reco:hitpdune:DisambigFromSpacePoints 0.125526 0.331855 0.643823 0.282879 0.158051 10
reco:pandora:StandardPandora 3.02546 6.05311 14.0016 4.67791 3.40115 10
reco:pandoraWriter:StandardPandora 0.0880278 0.120849 0.195596 0.105669 0.0373533 10
reco:pandoraTrack:LArPandoraTrackCreation 0.373862 0.533512 1.08023 0.468718 0.197593 10
reco:pandoraShower:LArPandoraShowerCreation 0.303818 0.569752 1.15542 0.487174 0.239233 10
reco:pandoracalo:Calorimetry 0.250534 0.340136 0.686472 0.303471 0.121718 10
reco:pandoracalonosce:Calorimetry 0.241753 0.32806 0.653677 0.291422 0.116432 10
reco:pandoraShowercalo:ShowerCalorimetry 0.328444 0.630389 1.37102 0.536018 0.288833 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.322486 0.620191 1.34655 0.525358 0.282034 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0250292 0.0317429 0.0447219 0.0294307 0.00567914 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0244056 0.0306454 0.0445309 0.0284591 0.0057982 10
reco:emtrkmichelid:EmTrackMichelId 11.8819 15.8137 23.299 14.9072 3.09612 10
[art]:TriggerResults:TriggerResultInserter 1.3927e-05 2.5964e-05 9.2328e-05 1.9422e-05 2.2292e-05 10
end_path:out1:RootOutput 8.867e-06 1.32303e-05 4.2141e-05 1.041e-05 9.66252e-06 10
end_path:out1:RootOutput(write) 0.610032 0.803464 1.08811 0.76654 0.138772 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6059.66 MB
Peak resident set size usage (VmHWM): 3893.82 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12738892
-rw-r--r-- 1 dunegli fnalgrid 231552 Dec 18 21:57 jobscript.log
-rw-r--r-- 1 dunegli fnalgrid 200 Dec 18 21:57 justin-processed-pfns.txt
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 18 21:57 reco_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 3574 Dec 18 21:57 Pandora_Geometry.xml
-rw-r--r-- 1 dunegli fnalgrid 139264 Dec 18 21:57 mem.db
-rw-r--r-- 1 dunegli fnalgrid 28672 Dec 18 21:57 time.db
-rw-r--r-- 1 dunegli fnalgrid 6607540359 Dec 18 21:57 pdhd_prod_beam__261612_52_1_20251212T155737Z_gen_g4_IonScintPDExt.root_268535_34_1_20251215T215017Z_PDInt.root_263305_25_1_20251218T205248Z_detsim_reco1.root
-rw-r--r-- 1 dunegli fnalgrid 39790320 Dec 18 21:56 Pandora_Events.pndr
-rw-r--r-- 1 dunegli fnalgrid 0 Dec 18 21:46 debugprod.log
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 18 21:45 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 6396836635 Dec 18 21:45 temp_detsim.root
-rw-r--r-- 1 dunegli fnalgrid 121 Dec 18 20:52 all-input-dids.txt
