Jobsub ID 268896.157@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ner0":22560 [22560] ] cmm:[ bad:0 lf_noisy:222 ]
[15:20:55.776] D [ glue ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=189 time=0 tick=512 with 42830 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":20270 [0] "wiener0":22560 [22560] ] cmm:[ bad:0 lf_noisy:222 ] input 1: frame: ident=189 time=0 tick=512 with 38862 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":16122 [0] "wiener1":22740 [22740] ] cmm:[ bad:0 ] input 2: frame: ident=189 time=0 tick=512 with 42384 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":18191 [0] "wiener2":24193 [24193] ] cmm:[ bad:0 ] input 3: frame: ident=189 time=0 tick=512 with 36534 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":16349 [0] "wiener3":20185 [20185] ] cmm:[ bad:0 ] output: frame: ident=189 time=0 tick=512 with 160610 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":20270 [0] "gauss1":16122 [0] "gauss2":18191 [0] "gauss3":16349 [0] "wiener0":22560 [22560] "wiener1":22740 [22740] "wiener2":24193 [24193] "wiener3":20185 [20185] ] cmm:[ bad:0 lf_noisy:222 ]
Retagger: tagging trace set: wiener with 89678 traces, 89678 summary
Retagger: tagging trace set: gauss with 70932 traces, 0 summary
[15:20:55.792] D [ glue ] sink frame: #189 @0 with 160610 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[15:20:55.793] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[15:20:55.793] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[15:20:55.793] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[15:20:55.793] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[15:20:55.793] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[15:20:55.793] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[15:20:55.793] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[15:20:55.793] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[15:20:55.793] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[15:20:55.793] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[15:20:55.793] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[15:20:55.793] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[15:20:55.793] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[15:20:55.793] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[15:20:55.793] D [ glue ] frame sink sees EOS
[15:20:55.793] D [ pgraph ] <Pgrapher:> graph execution complete
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.21 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.02 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.97 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.51 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.2 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.98 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.98 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.91 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[15:20:55.793] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[15:20:55.793] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:20:55.793] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[15:20:55.793] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:20:55.793] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[15:20:55.793] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[15:20:55.793] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[15:20:55.793] I [ timer ] Timer: Total node execution : 51.84000116214156 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 70932 traces tagged "gauss"
FrameSaver: q=2.24025e+06 n=1172885 tag=gauss
wclsFrameSaver: saving 89678 traces tagged "wiener"
FrameSaver: q=2.71481e+06 n=1137646 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 16135 XUs and 37807 XVs -> 1092 XUVs
C:0 T:3 5 XUs and 7 XVs -> 3 XUVs
C:0 T:5 23629 XUs and 19070 XVs -> 667 XUVs
C:0 T:6 12512 XUs and 17293 XVs -> 463 XUVs
C:0 T:7 4 XUs and 5 XVs -> 0 XUVs
2225 XUVs total
1349 collection wire objects
2225 potential space points
Neighbour search...
31325 tests to find 19100 neighbours
Iterating with no regularization...
Begin: 1.95171e+07
0 1.86017e+07
1 1.85473e+07
2 1.85413e+07
Now with regularization...
Begin: 1.72383e+07
0 1.72372e+07
17-Dec-2025 15:21:34 CST Closed output file "pdhd_prod_beam__267379_165_1_20251212T162420Z_gen_g4_IonScintPDExt.root_262726_125_1_20251215T212753Z_PDInt.root_268896_157_1_20251217T193656Z_detsim_reco1.root"
17-Dec-2025 15:21:34 CST Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 66.9334 99.1677 131.567 98.7304 17.7815 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00111159 0.00471615 0.00733317 0.00469982 0.00214677 10
reco:wclsdatahd:WireCellToolkit 45.7199 51.8873 55.8961 52.0081 2.73557 10
reco:wirefilter:WireFilter 0.0145157 0.0308902 0.0510623 0.0279094 0.0109631 10
reco:gaushit:GausHitFinder 0.415234 0.795783 1.23292 0.786168 0.239124 10
reco:nhitsfilter:NumberOfHitsFilter 0.00016463 0.000362107 0.000725548 0.000319528 0.000140798 10
reco:reco3d:SpacePointSolver 0.148933 0.947806 2.01329 0.73734 0.631691 10
reco:hitpdune:DisambigFromSpacePoints 0.149962 0.478703 0.726846 0.535491 0.19345 10
reco:pandora:StandardPandora 3.45128 11.2034 23.572 10.0654 5.88605 10
reco:pandoraWriter:StandardPandora 0.161167 0.281901 0.398184 0.284023 0.0834284 10
reco:pandoraTrack:LArPandoraTrackCreation 0.314142 1.16212 2.69401 1.0512 0.619253 10
reco:pandoraShower:LArPandoraShowerCreation 0.270825 1.32372 3.35898 1.381 0.849485 10
reco:pandoracalo:Calorimetry 0.206909 0.668415 1.44372 0.618277 0.303258 10
reco:pandoracalonosce:Calorimetry 0.202973 0.706818 1.7309 0.649302 0.382271 10
reco:pandoraShowercalo:ShowerCalorimetry 0.308352 1.26943 3.43966 1.17319 0.830124 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.260673 1.13007 3.44905 1.00795 0.846646 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0312526 0.0570783 0.0926797 0.0580617 0.0199613 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0305389 0.051143 0.0845659 0.0492177 0.0173032 10
reco:emtrkmichelid:EmTrackMichelId 14.3061 25.674 34.874 25.2513 6.88471 10
[art]:TriggerResults:TriggerResultInserter 3.4966e-05 5.75345e-05 0.000112372 4.92525e-05 2.43824e-05 10
end_path:out1:RootOutput 1.055e-05 1.38803e-05 3.0217e-05 1.2098e-05 5.5493e-06 10
end_path:out1:RootOutput(write) 0.879252 1.40503 1.99397 1.34421 0.317532 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6018.7 MB
Peak resident set size usage (VmHWM): 3827.76 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12579876
-rw-r--r-- 1 dune osgvo 234289 Dec 17 15:21 jobscript.log
-rw-r--r-- 1 dune osgvo 202 Dec 17 15:21 justin-processed-pfns.txt
-rw-r--r-- 1 dune osgvo 519 Dec 17 15:21 reco_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 3574 Dec 17 15:21 Pandora_Geometry.xml
-rw-r--r-- 1 dune osgvo 139264 Dec 17 15:21 mem.db
-rw-r--r-- 1 dune osgvo 28672 Dec 17 15:21 time.db
-rw-r--r-- 1 dune osgvo 6521834962 Dec 17 15:21 pdhd_prod_beam__267379_165_1_20251212T162420Z_gen_g4_IonScintPDExt.root_262726_125_1_20251215T212753Z_PDInt.root_268896_157_1_20251217T193656Z_detsim_reco1.root
-rw-r--r-- 1 dune osgvo 37540820 Dec 17 15:21 Pandora_Events.pndr
-rw-r--r-- 1 dune osgvo 0 Dec 17 15:03 debugprod.log
-rw-r--r-- 1 dune osgvo 519 Dec 17 15:02 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 6321959425 Dec 17 15:02 temp_detsim.root
-rw-r--r-- 1 dune osgvo 123 Dec 17 13:36 all-input-dids.txt
