Jobsub ID 263105.1@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
sets:[ "gauss0":23619 [0] "wiener0":25756 [25756] ] cmm:[ bad:0 lf_noisy:229 ]
[14:56:34.074] D [ glue ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=169 time=0 tick=512 with 49375 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":23619 [0] "wiener0":25756 [25756] ] cmm:[ bad:0 lf_noisy:229 ] input 1: frame: ident=169 time=0 tick=512 with 37783 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":16154 [0] "wiener1":21629 [21629] ] cmm:[ bad:0 ] input 2: frame: ident=169 time=0 tick=512 with 55043 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":24289 [0] "wiener2":30754 [30754] ] cmm:[ bad:0 lf_noisy:1 ] input 3: frame: ident=169 time=0 tick=512 with 23859 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":11412 [0] "wiener3":12447 [12447] ] cmm:[ bad:0 ] output: frame: ident=169 time=0 tick=512 with 166060 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":23619 [0] "gauss1":16154 [0] "gauss2":24289 [0] "gauss3":11412 [0] "wiener0":25756 [25756] "wiener1":21629 [21629] "wiener2":30754 [30754] "wiener3":12447 [12447] ] cmm:[ bad:0 lf_noisy:230 ]
Retagger: tagging trace set: wiener with 90586 traces, 90586 summary
Retagger: tagging trace set: gauss with 75474 traces, 0 summary
[14:56:34.090] D [ glue ] sink frame: #169 @0 with 166060 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[14:56:34.090] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[14:56:34.090] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[14:56:34.090] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[14:56:34.090] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[14:56:34.090] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[14:56:34.090] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[14:56:34.090] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[14:56:34.090] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[14:56:34.090] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[14:56:34.090] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[14:56:34.090] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[14:56:34.090] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[14:56:34.090] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[14:56:34.090] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[14:56:34.090] D [ glue ] frame sink sees EOS
[14:56:34.091] D [ pgraph ] <Pgrapher:> graph execution complete
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.14 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.09 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.47 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.25 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.34 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.29 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.28 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.12 sec
[14:56:34.091] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:56:34.091] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:56:34.091] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:56:34.091] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:56:34.091] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:56:34.091] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:56:34.091] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:56:34.091] I [ timer ] Timer: Total node execution : 53.03000049479306 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 75474 traces tagged "gauss"
FrameSaver: q=2.19902e+06 n=1196133 tag=gauss
wclsFrameSaver: saving 90586 traces tagged "wiener"
FrameSaver: q=2.75863e+06 n=1174170 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 52609 XUs and 32629 XVs -> 1506 XUVs
C:0 T:4 8 XUs and 9 XVs -> 3 XUVs
C:0 T:5 73575 XUs and 44452 XVs -> 1381 XUVs
C:0 T:6 5829 XUs and 5250 XVs -> 340 XUVs
3230 XUVs total
1762 collection wire objects
3230 potential space points
Neighbour search...
72862 tests to find 37222 neighbours
Iterating with no regularization...
Begin: 1.9877e+07
0 1.76637e+07
1 1.75876e+07
2 1.75782e+07
Now with regularization...
Begin: 1.63281e+07
0 1.63249e+07
17-Dec-2025 14:57:19 CST Closed output file "pdhd_prod_beam__259199_36_1_20251208T212634Z_gen_g4_IonScintPDExt.root_266057_47_1_20251210T192243Z_PDInt.root_263105_1_1_20251217T191334Z_detsim_reco1.root"
17-Dec-2025 14:57:19 CST Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 78.6867 98.9436 140.844 98.9915 16.6693 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.0015135 0.00403376 0.00623472 0.00481664 0.00156531 10
reco:wclsdatahd:WireCellToolkit 48.0942 51.7953 55.2869 52.2386 2.00827 10
reco:wirefilter:WireFilter 0.0129021 0.0202896 0.0285757 0.0193432 0.00473718 10
reco:gaushit:GausHitFinder 0.437349 0.707002 1.12628 0.689747 0.221589 10
reco:nhitsfilter:NumberOfHitsFilter 0.000211787 0.000341413 0.000609844 0.000283878 0.000131679 10
reco:reco3d:SpacePointSolver 0.16696 0.558215 1.09251 0.544498 0.278592 10
reco:hitpdune:DisambigFromSpacePoints 0.122336 0.541436 1.14884 0.508309 0.31186 10
reco:pandora:StandardPandora 4.24916 11.5196 37.7472 10.3573 9.15886 10
reco:pandoraWriter:StandardPandora 0.157373 0.239256 0.327702 0.243091 0.0642379 10
reco:pandoraTrack:LArPandoraTrackCreation 0.414097 1.02135 1.67873 0.96598 0.468914 10
reco:pandoraShower:LArPandoraShowerCreation 0.256044 1.02205 2.17773 1.10916 0.535186 10
reco:pandoracalo:Calorimetry 0.20797 0.572937 1.13311 0.55693 0.270282 10
reco:pandoracalonosce:Calorimetry 0.197504 0.579604 1.16033 0.576254 0.284568 10
reco:pandoraShowercalo:ShowerCalorimetry 0.230136 1.05056 1.7673 1.17655 0.502172 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.226559 0.97384 1.68168 1.09103 0.432127 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0279561 0.0586157 0.0855358 0.0587203 0.0177392 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0271799 0.0572493 0.0862216 0.055471 0.0176799 10
reco:emtrkmichelid:EmTrackMichelId 17.7277 26.7314 36.1541 25.0895 5.95387 10
[art]:TriggerResults:TriggerResultInserter 2.4696e-05 4.33255e-05 0.000115928 3.4104e-05 2.4984e-05 10
end_path:out1:RootOutput 8.376e-06 1.26459e-05 3.7961e-05 9.9235e-06 8.47942e-06 10
end_path:out1:RootOutput(write) 0.920886 1.42951 1.88276 1.37746 0.28439 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6311.29 MB
Peak resident set size usage (VmHWM): 4121.49 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12585364
-rw-r--r-- 1 dune osgvo 231374 Dec 17 14:57 jobscript.log
-rw-r--r-- 1 dune osgvo 200 Dec 17 14:57 justin-processed-pfns.txt
-rw-r--r-- 1 dune osgvo 519 Dec 17 14:57 reco_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 3574 Dec 17 14:57 Pandora_Geometry.xml
-rw-r--r-- 1 dune osgvo 139264 Dec 17 14:57 mem.db
-rw-r--r-- 1 dune osgvo 28672 Dec 17 14:57 time.db
-rw-r--r-- 1 dune osgvo 6525004471 Dec 17 14:57 pdhd_prod_beam__259199_36_1_20251208T212634Z_gen_g4_IonScintPDExt.root_266057_47_1_20251210T192243Z_PDInt.root_263105_1_1_20251217T191334Z_detsim_reco1.root
-rw-r--r-- 1 dune osgvo 38749170 Dec 17 14:56 Pandora_Events.pndr
-rw-r--r-- 1 dune osgvo 0 Dec 17 14:38 debugprod.log
-rw-r--r-- 1 dune osgvo 519 Dec 17 14:38 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 6323206689 Dec 17 14:38 temp_detsim.root
-rw-r--r-- 1 dune osgvo 121 Dec 17 13:13 all-input-dids.txt
