Jobsub ID 263124.0@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
ith 53280 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":25159 [0] "wiener0":28121 [28121] ] cmm:[ bad:0 lf_noisy:299 ]
[17:44:58.525] D [ glue ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=129 time=0 tick=512 with 53280 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":25159 [0] "wiener0":28121 [28121] ] cmm:[ bad:0 lf_noisy:299 ] input 1: frame: ident=129 time=0 tick=512 with 42692 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":19596 [0] "wiener1":23096 [23096] ] cmm:[ bad:0 ] input 2: frame: ident=129 time=0 tick=512 with 27894 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":12953 [0] "wiener2":14941 [14941] ] cmm:[ bad:0 ] input 3: frame: ident=129 time=0 tick=512 with 24408 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":11260 [0] "wiener3":13148 [13148] ] cmm:[ bad:0 ] output: frame: ident=129 time=0 tick=512 with 148274 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":25159 [0] "gauss1":19596 [0] "gauss2":12953 [0] "gauss3":11260 [0] "wiener0":28121 [28121] "wiener1":23096 [23096] "wiener2":14941 [14941] "wiener3":13148 [13148] ] cmm:[ bad:0 lf_noisy:299 ]
Retagger: tagging trace set: wiener with 79306 traces, 79306 summary
Retagger: tagging trace set: gauss with 68968 traces, 0 summary
[17:44:58.540] D [ glue ] sink frame: #129 @0 with 148274 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[17:44:58.540] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[17:44:58.540] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[17:44:58.540] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[17:44:58.540] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[17:44:58.540] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[17:44:58.540] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[17:44:58.540] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[17:44:58.540] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[17:44:58.541] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[17:44:58.541] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[17:44:58.541] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[17:44:58.541] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[17:44:58.541] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[17:44:58.541] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[17:44:58.541] D [ glue ] frame sink sees EOS
[17:44:58.541] D [ pgraph ] <Pgrapher:> graph execution complete
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.34 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.06 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.78 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.76 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.09 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.91 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.75 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.46 sec
[17:44:58.541] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:44:58.541] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[17:44:58.541] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:58.541] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:44:58.541] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[17:44:58.541] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[17:44:58.541] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:44:58.541] I [ timer ] Timer: Total node execution : 43.19000057131052 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 68968 traces tagged "gauss"
FrameSaver: q=1.94414e+06 n=1116024 tag=gauss
wclsFrameSaver: saving 79306 traces tagged "wiener"
FrameSaver: q=2.42246e+06 n=1092373 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 24679 XUs and 17642 XVs -> 659 XUVs
C:0 T:5 6450 XUs and 6877 XVs -> 208 XUVs
C:0 T:6 5061 XUs and 4803 XVs -> 191 XUVs
1058 XUVs total
909 collection wire objects
1058 potential space points
Neighbour search...
7304 tests to find 3618 neighbours
Iterating with no regularization...
Begin: 1.25607e+07
0 1.22786e+07
1 1.22753e+07
Now with regularization...
Begin: 1.16207e+07
0 1.16206e+07
17-Dec-2025 17:45:25 EST Closed output file "pdhd_prod_beam__265003_98_1_20251208T181205Z_gen_g4_IonScintPDExt.root_265463_5_1_20251209T161324Z_PDInt.root_263124_0_1_20251217T205455Z_detsim_reco1.root"
17-Dec-2025 17:45:25 EST Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 70.4487 93.2123 112.392 90.8605 12.1378 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00137836 0.00362588 0.0060339 0.00382756 0.00137322 10
reco:wclsdatahd:WireCellToolkit 44.957 52.3773 58.618 53.1612 4.35476 10
reco:wirefilter:WireFilter 0.0133905 0.0233437 0.0444368 0.0203694 0.0106875 10
reco:gaushit:GausHitFinder 0.432199 0.617908 0.976047 0.552909 0.163901 10
reco:nhitsfilter:NumberOfHitsFilter 0.000172975 0.00033058 0.000537689 0.000283553 0.000122705 10
reco:reco3d:SpacePointSolver 0.139606 0.518258 1.07712 0.461601 0.289539 10
reco:hitpdune:DisambigFromSpacePoints 0.201822 0.448585 0.747904 0.41421 0.181083 10
reco:pandora:StandardPandora 4.19094 7.87387 10.929 7.57355 2.52906 10
reco:pandoraWriter:StandardPandora 0.158996 0.248146 0.311084 0.24545 0.0404355 10
reco:pandoraTrack:LArPandoraTrackCreation 0.482196 0.895583 1.29577 0.907938 0.231166 10
reco:pandoraShower:LArPandoraShowerCreation 0.450473 0.867846 1.18759 0.88363 0.214196 10
reco:pandoracalo:Calorimetry 0.291955 0.482275 0.681266 0.479685 0.125238 10
reco:pandoracalonosce:Calorimetry 0.208853 0.452541 0.65107 0.444647 0.127256 10
reco:pandoraShowercalo:ShowerCalorimetry 0.349173 0.841611 1.42147 0.823196 0.255936 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.410304 0.856006 1.36749 0.848288 0.246867 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0375325 0.0562764 0.072024 0.057579 0.0107668 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0365642 0.0558844 0.0699613 0.0583523 0.0106366 10
reco:emtrkmichelid:EmTrackMichelId 16.4257 25.221 38.2866 23.7575 5.54864 10
[art]:TriggerResults:TriggerResultInserter 3.0137e-05 4.65634e-05 0.000118593 3.78665e-05 2.46601e-05 10
end_path:out1:RootOutput 7.043e-06 1.09965e-05 3.0457e-05 8.952e-06 6.52767e-06 10
end_path:out1:RootOutput(write) 1.00138 1.30633 1.62079 1.34385 0.226999 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5827.12 MB
Peak resident set size usage (VmHWM): 3643.08 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12193788
-rw-r--r--. 1 dune osgvo 231102 Dec 17 17:45 jobscript.log
-rw-r--r--. 1 dune osgvo 193 Dec 17 17:45 justin-processed-pfns.txt
-rw-r--r--. 1 dune osgvo 519 Dec 17 17:45 reco_protodunehd_hist.root
-rw-r--r--. 1 dune osgvo 3574 Dec 17 17:45 Pandora_Geometry.xml
-rw-r--r--. 1 dune osgvo 139264 Dec 17 17:45 mem.db
-rw-r--r--. 1 dune osgvo 28672 Dec 17 17:45 time.db
-rw-r--r--. 1 dune osgvo 6320477867 Dec 17 17:45 pdhd_prod_beam__265003_98_1_20251208T181205Z_gen_g4_IonScintPDExt.root_265463_5_1_20251209T161324Z_PDInt.root_263124_0_1_20251217T205455Z_detsim_reco1.root
-rw-r--r--. 1 dune osgvo 35042830 Dec 17 17:45 Pandora_Events.pndr
-rw-r--r--. 1 dune osgvo 0 Dec 17 17:28 debugprod.log
-rw-r--r--. 1 dune osgvo 519 Dec 17 17:27 detsim_single_protodunehd_hist.root
-rw-r--r--. 1 dune osgvo 6130468849 Dec 17 17:27 temp_detsim.root
-rw-r--r--. 1 dune osgvo 120 Dec 17 15:54 all-input-dids.txt
