Jobsub ID 268896.169@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
0 lf_noisy:280 ]
[15:32:56.815] D [ glue ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=189 time=0 tick=512 with 38668 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":18976 [0] "wiener0":19692 [19692] ] cmm:[ bad:0 lf_noisy:280 ] input 1: frame: ident=189 time=0 tick=512 with 53770 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":23023 [0] "wiener1":30747 [30747] ] cmm:[ bad:0 ] input 2: frame: ident=189 time=0 tick=512 with 29190 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":12856 [0] "wiener2":16334 [16334] ] cmm:[ bad:0 ] input 3: frame: ident=189 time=0 tick=512 with 48222 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":20613 [0] "wiener3":27609 [27609] ] cmm:[ bad:0 ] output: frame: ident=189 time=0 tick=512 with 169850 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":18976 [0] "gauss1":23023 [0] "gauss2":12856 [0] "gauss3":20613 [0] "wiener0":19692 [19692] "wiener1":30747 [30747] "wiener2":16334 [16334] "wiener3":27609 [27609] ] cmm:[ bad:0 lf_noisy:280 ]
Retagger: tagging trace set: wiener with 94382 traces, 94382 summary
Retagger: tagging trace set: gauss with 75468 traces, 0 summary
[15:32:56.832] D [ glue ] sink frame: #189 @0 with 169850 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[15:32:56.832] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[15:32:56.832] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[15:32:56.832] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[15:32:56.832] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[15:32:56.833] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[15:32:56.833] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[15:32:56.833] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[15:32:56.833] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[15:32:56.833] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[15:32:56.833] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[15:32:56.833] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[15:32:56.833] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[15:32:56.833] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[15:32:56.833] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[15:32:56.833] D [ glue ] frame sink sees EOS
[15:32:56.833] D [ pgraph ] <Pgrapher:> graph execution complete
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.59 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.27 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.15 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.8 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.34 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.32 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.26 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.25 sec
[15:32:56.833] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[15:32:56.833] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:32:56.833] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:32:56.833] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:32:56.833] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[15:32:56.833] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[15:32:56.833] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[15:32:56.833] I [ timer ] Timer: Total node execution : 58.05000025592744 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 75468 traces tagged "gauss"
FrameSaver: q=2.48522e+06 n=1267855 tag=gauss
wclsFrameSaver: saving 94382 traces tagged "wiener"
FrameSaver: q=2.99223e+06 n=1233641 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 36523 XUs and 71097 XVs -> 6581 XUVs
C:0 T:3 50 XUs and 75 XVs -> 25 XUVs
C:0 T:5 5593 XUs and 7703 XVs -> 1130 XUVs
C:0 T:6 24200 XUs and 29004 XVs -> 1819 XUVs
C:0 T:7 10 XUs and 15 XVs -> 3 XUVs
9558 XUVs total
2186 collection wire objects
9558 potential space points
Neighbour search...
1952798 tests to find 883452 neighbours
Iterating with no regularization...
Begin: 3.85617e+07
0 3.30491e+07
1 3.26325e+07
2 3.25782e+07
3 3.25675e+07
Now with regularization...
Begin: 3.01881e+07
0 3.0179e+07
17-Dec-2025 15:33:47 CST Closed output file "pdhd_prod_beam__267379_42_1_20251212T160936Z_gen_g4_IonScintPDExt.root_262726_85_1_20251215T212455Z_PDInt.root_268896_169_1_20251217T194428Z_detsim_reco1.root"
17-Dec-2025 15:33:47 CST Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 83.2546 99.8689 116.046 97.8306 9.8001 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00101857 0.00819632 0.0419822 0.00476078 0.0113654 10
reco:wclsdatahd:WireCellToolkit 52.6138 57.4753 60.4316 57.7936 2.44516 10
reco:wirefilter:WireFilter 0.0181241 0.0258687 0.0300061 0.0268527 0.00360127 10
reco:gaushit:GausHitFinder 0.440721 0.719736 1.01317 0.728178 0.171843 10
reco:nhitsfilter:NumberOfHitsFilter 0.000240091 0.000408199 0.000769826 0.000361099 0.000146207 10
reco:reco3d:SpacePointSolver 0.27361 0.677996 1.57384 0.609999 0.385747 10
reco:hitpdune:DisambigFromSpacePoints 0.177052 0.431364 0.888712 0.390921 0.210164 10
reco:pandora:StandardPandora 4.48119 10.2756 17.1832 8.24218 3.8867 10
reco:pandoraWriter:StandardPandora 0.15894 0.228119 0.334322 0.214574 0.0543414 10
reco:pandoraTrack:LArPandoraTrackCreation 0.368519 0.876032 1.35215 0.848485 0.278378 10
reco:pandoraShower:LArPandoraShowerCreation 0.397383 0.945483 1.90854 0.876674 0.457192 10
reco:pandoracalo:Calorimetry 0.227709 0.51132 0.766785 0.521649 0.15016 10
reco:pandoracalonosce:Calorimetry 0.22138 0.48891 0.750957 0.465512 0.144513 10
reco:pandoraShowercalo:ShowerCalorimetry 0.38908 0.941686 1.82253 0.83484 0.441068 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.380065 0.947967 1.71645 0.990167 0.42229 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0399229 0.0619059 0.0788286 0.0642869 0.0132158 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0391861 0.0561802 0.0795054 0.0599121 0.0134998 10
reco:emtrkmichelid:EmTrackMichelId 16.6624 23.7348 31.0778 22.7664 4.3587 10
[art]:TriggerResults:TriggerResultInserter 2.9244e-05 5.489e-05 7.9139e-05 5.4968e-05 1.44915e-05 10
end_path:out1:RootOutput 4.929e-06 1.3799e-05 3.3694e-05 1.0865e-05 8.49984e-06 10
end_path:out1:RootOutput(write) 1.01349 1.39989 1.87356 1.30174 0.253372 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6543.82 MB
Peak resident set size usage (VmHWM): 4359.24 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 11856340
-rw-r--r--. 1 dune osgvo 231028 Dec 17 15:33 jobscript.log
-rw-r--r--. 1 dune osgvo 194 Dec 17 15:33 justin-processed-pfns.txt
-rw-r--r--. 1 dune osgvo 519 Dec 17 15:33 reco_protodunehd_hist.root
-rw-r--r--. 1 dune osgvo 3574 Dec 17 15:33 Pandora_Geometry.xml
-rw-r--r--. 1 dune osgvo 139264 Dec 17 15:33 mem.db
-rw-r--r--. 1 dune osgvo 28672 Dec 17 15:33 time.db
-rw-r--r--. 1 dune osgvo 6145213111 Dec 17 15:33 pdhd_prod_beam__267379_42_1_20251212T160936Z_gen_g4_IonScintPDExt.root_262726_85_1_20251215T212455Z_PDInt.root_268896_169_1_20251217T194428Z_detsim_reco1.root
-rw-r--r--. 1 dune osgvo 32770230 Dec 17 15:33 Pandora_Events.pndr
-rw-r--r--. 1 dune osgvo 0 Dec 17 15:15 debugprod.log
-rw-r--r--. 1 dune osgvo 519 Dec 17 15:14 detsim_single_protodunehd_hist.root
-rw-r--r--. 1 dune osgvo 5962459768 Dec 17 15:14 temp_detsim.root
-rw-r--r--. 1 dune osgvo 121 Dec 17 13:44 all-input-dids.txt
