Jobsub ID 268896.154@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
bad:0 lf_noisy:178 ]
[15:21:05.160] D [ glue ] <FrameFanin:sn_mag_nf> call=18 input 0: frame: ident=189 time=0 tick=512 with 62903 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":29551 [0] "wiener0":33352 [33352] ] cmm:[ bad:0 lf_noisy:178 ] input 1: frame: ident=189 time=0 tick=512 with 59916 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":24987 [0] "wiener1":34929 [34929] ] cmm:[ bad:0 ] input 2: frame: ident=189 time=0 tick=512 with 64451 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":26877 [0] "wiener2":37574 [37574] ] cmm:[ bad:0 ] input 3: frame: ident=189 time=0 tick=512 with 35179 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":16317 [0] "wiener3":18862 [18862] ] cmm:[ bad:0 ] output: frame: ident=189 time=0 tick=512 with 222449 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":29551 [0] "gauss1":24987 [0] "gauss2":26877 [0] "gauss3":16317 [0] "wiener0":33352 [33352] "wiener1":34929 [34929] "wiener2":37574 [37574] "wiener3":18862 [18862] ] cmm:[ bad:0 lf_noisy:178 ]
Retagger: tagging trace set: wiener with 124717 traces, 124717 summary
Retagger: tagging trace set: gauss with 97732 traces, 0 summary
[15:21:05.202] D [ glue ] sink frame: #189 @0 with 222449 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[15:21:05.202] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[15:21:05.202] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[15:21:05.202] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[15:21:05.202] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[15:21:05.202] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[15:21:05.202] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[15:21:05.202] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[15:21:05.202] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[15:21:05.202] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[15:21:05.202] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[15:21:05.202] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[15:21:05.202] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[15:21:05.202] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[15:21:05.202] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[15:21:05.202] D [ glue ] frame sink sees EOS
[15:21:05.202] D [ pgraph ] <Pgrapher:> graph execution complete
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 23.87 sec
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.8 sec
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.7 sec
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.29 sec
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 4.34 sec
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 4.18 sec
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.84 sec
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 3.74 sec
[15:21:05.202] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.06 sec
[15:21:05.202] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[15:21:05.202] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:21:05.202] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[15:21:05.203] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:21:05.203] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[15:21:05.203] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[15:21:05.203] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[15:21:05.203] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[15:21:05.203] I [ timer ] Timer: Total node execution : 87.84999975003302 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 97732 traces tagged "gauss"
FrameSaver: q=3.52251e+06 n=1711265 tag=gauss
wclsFrameSaver: saving 124717 traces tagged "wiener"
FrameSaver: q=4.13514e+06 n=1632056 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 86083 XUs and 141863 XVs -> 14176 XUVs
C:0 T:3 39 XUs and 23 XVs -> 0 XUVs
C:0 T:4 13 XUs and 27 XVs -> 2 XUVs
C:0 T:5 53685 XUs and 50307 XVs -> 2414 XUVs
C:0 T:6 9487 XUs and 10109 XVs -> 487 XUVs
17079 XUVs total
3433 collection wire objects
17079 potential space points
Neighbour search...
3942929 tests to find 1616666 neighbours
Iterating with no regularization...
Begin: 9.09824e+07
0 7.47894e+07
1 7.38194e+07
2 7.36749e+07
3 7.36328e+07
Now with regularization...
Begin: 6.8856e+07
0 6.88217e+07
17-Dec-2025 15:22:24 CST Closed output file "pdhd_prod_beam__267379_100_1_20251212T162044Z_gen_g4_IonScintPDExt.root_262726_144_1_20251215T213626Z_PDInt.root_268896_154_1_20251217T193712Z_detsim_reco1.root"
17-Dec-2025 15:22:24 CST Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 107.448 133.455 168.798 131.282 17.9606 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00137767 0.00628286 0.0115259 0.00640384 0.00334846 10
reco:wclsdatahd:WireCellToolkit 73.9272 86.4446 96.572 87.0007 6.02111 10
reco:wirefilter:WireFilter 0.0263475 0.0425693 0.0677698 0.0392888 0.0128414 10
reco:gaushit:GausHitFinder 0.644298 1.01992 1.68597 0.92072 0.349176 10
reco:nhitsfilter:NumberOfHitsFilter 0.000342322 0.000544707 0.000716735 0.000560313 0.000125269 10
reco:reco3d:SpacePointSolver 0.29529 0.954041 3.86849 0.514133 1.02278 10
reco:hitpdune:DisambigFromSpacePoints 0.371954 0.894647 2.1521 0.65417 0.509253 10
reco:pandora:StandardPandora 8.30981 14.1756 24.1543 13.3429 4.72898 10
reco:pandoraWriter:StandardPandora 0.251515 0.329161 0.398084 0.331625 0.0488346 10
reco:pandoraTrack:LArPandoraTrackCreation 1.46638 2.4483 3.73154 2.24422 0.687396 10
reco:pandoraShower:LArPandoraShowerCreation 1.07657 2.54823 5.14675 2.20161 1.27443 10
reco:pandoracalo:Calorimetry 0.863155 1.21573 2.18966 1.05331 0.403937 10
reco:pandoracalonosce:Calorimetry 0.701547 1.13924 1.8539 1.02541 0.33904 10
reco:pandoraShowercalo:ShowerCalorimetry 1.07454 2.41904 4.7989 1.98218 1.21776 10
reco:pandoraShowercalonosce:ShowerCalorimetry 1.39515 2.37946 4.44938 2.10052 1.02513 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0608603 0.0843738 0.126845 0.0711189 0.0225541 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0457709 0.0826255 0.136202 0.077754 0.0276589 10
reco:emtrkmichelid:EmTrackMichelId 11.3733 15.1183 20.5335 15.0793 3.00292 10
[art]:TriggerResults:TriggerResultInserter 4.8981e-05 7.59433e-05 0.000154601 6.6865e-05 2.96333e-05 10
end_path:out1:RootOutput 1.0554e-05 1.66367e-05 5.3507e-05 1.23665e-05 1.24326e-05 10
end_path:out1:RootOutput(write) 1.67041 2.06999 2.97407 1.91358 0.414736 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6586.31 MB
Peak resident set size usage (VmHWM): 4142.02 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 12121256
-rw-r--r-- 1 dune osgvo 231605 Dec 17 15:22 jobscript.log
-rw-r--r-- 1 dune osgvo 202 Dec 17 15:22 justin-processed-pfns.txt
-rw-r--r-- 1 dune osgvo 519 Dec 17 15:22 reco_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 3574 Dec 17 15:22 Pandora_Geometry.xml
-rw-r--r-- 1 dune osgvo 139264 Dec 17 15:22 mem.db
-rw-r--r-- 1 dune osgvo 28672 Dec 17 15:22 time.db
-rw-r--r-- 1 dune osgvo 6286403923 Dec 17 15:22 pdhd_prod_beam__267379_100_1_20251212T162044Z_gen_g4_IonScintPDExt.root_262726_144_1_20251215T213626Z_PDInt.root_268896_154_1_20251217T193712Z_detsim_reco1.root
-rw-r--r-- 1 dune osgvo 35895550 Dec 17 15:21 Pandora_Events.pndr
-rw-r--r-- 1 dune osgvo 0 Dec 17 14:57 debugprod.log
-rw-r--r-- 1 dune osgvo 519 Dec 17 14:57 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 6089411323 Dec 17 14:57 temp_detsim.root
-rw-r--r-- 1 dune osgvo 123 Dec 17 13:37 all-input-dids.txt
