Jobsub ID 268923.7@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
k=512 with 42922 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss0":20432 [0] "wiener0":22490 [22490] ] cmm:[ bad:0 lf_noisy:105 ] input 1: frame: ident=189 time=0 tick=512 with 35677 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss1":15373 [0] "wiener1":20304 [20304] ] cmm:[ bad:0 ] input 2: frame: ident=189 time=0 tick=512 with 38403 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss2":16752 [0] "wiener2":21651 [21651] ] cmm:[ bad:0 lf_noisy:1 ] input 3: frame: ident=189 time=0 tick=512 with 28817 traces. frame tags:[ "sigproc" ] 2 tagged trace sets:[ "gauss3":13283 [0] "wiener3":15534 [15534] ] cmm:[ bad:0 ] output: frame: ident=189 time=0 tick=512 with 145819 traces. frame tags:[ "framefanin" ] 8 tagged trace sets:[ "gauss0":20432 [0] "gauss1":15373 [0] "gauss2":16752 [0] "gauss3":13283 [0] "wiener0":22490 [22490] "wiener1":20304 [20304] "wiener2":21651 [21651] "wiener3":15534 [15534] ] cmm:[ bad:0 lf_noisy:106 ]
Retagger: tagging trace set: wiener with 79979 traces, 79979 summary
Retagger: tagging trace set: gauss with 65840 traces, 0 summary
[21:58:01.107] D [ glue ] sink frame: #189 @0 with 145819 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[21:58:01.107] D [ glue ] <FrameFanout:sn_mag_nf> call=19: see EOS
[21:58:01.107] D [ glue ] <ChannelSelector:chsel3> see EOS at call=9
[21:58:01.107] D [sigproc ] <OmnibusNoiseFilter:nf3> EOS at call=19
[21:58:01.107] D [sigproc ] <OmnibusSigProc:apa3sigproc3> EOS at call=19 anode=3
[21:58:01.107] D [ glue ] <ChannelSelector:chsel2> see EOS at call=9
[21:58:01.107] D [sigproc ] <OmnibusNoiseFilter:nf2> EOS at call=19
[21:58:01.107] D [sigproc ] <OmnibusSigProc:apa2sigproc2> EOS at call=19 anode=2
[21:58:01.107] D [ glue ] <ChannelSelector:chsel1> see EOS at call=9
[21:58:01.107] D [sigproc ] <OmnibusNoiseFilter:nf1> EOS at call=19
[21:58:01.107] D [sigproc ] <OmnibusSigProc:apa1sigproc1> EOS at call=19 anode=1
[21:58:01.107] D [ glue ] <ChannelSelector:chsel0> see EOS at call=9
[21:58:01.107] D [sigproc ] <OmnibusNoiseFilter:nf0> EOS at call=19
[21:58:01.107] D [sigproc ] <OmnibusSigProc:apa0sigproc0> EOS at call=19 anode=0
[21:58:01.107] D [ glue ] <FrameFanin:sn_mag_nf> EOS at call=19 with 4
[21:58:01.107] D [ glue ] frame sink sees EOS
[21:58:01.107] D [ pgraph ] <Pgrapher:> graph execution complete
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.47 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.69 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.68 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.5 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.05 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.03 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.03 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.02 sec
[21:58:01.107] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:58:01.107] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[21:58:01.107] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:58:01.107] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:58:01.107] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:58:01.107] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[21:58:01.107] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:58:01.107] I [ timer ] Timer: Total node execution : 33.48000002838671 sec
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 65840 traces tagged "gauss"
FrameSaver: q=1.93192e+06 n=1004290 tag=gauss
wclsFrameSaver: saving 79979 traces tagged "wiener"
FrameSaver: q=2.45964e+06 n=990059 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:2 27005 XUs and 32971 XVs -> 9622 XUVs
C:0 T:3 18 XUs and 24 XVs -> 0 XUVs
C:0 T:5 25017 XUs and 21833 XVs -> 491 XUVs
C:0 T:6 12215 XUs and 5602 XVs -> 201 XUVs
10314 XUVs total
957 collection wire objects
10314 potential space points
Neighbour search...
2696274 tests to find 1228526 neighbours
Iterating with no regularization...
Begin: 1.75133e+07
0 1.5431e+07
1 1.52808e+07
2 1.52467e+07
3 1.52336e+07
Now with regularization...
Begin: 1.40732e+07
0 1.40566e+07
1 1.40441e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
17-Dec-2025 21:58:19 UTC Closed output file "pdhd_prod_beam__267379_97_1_20251212T162205Z_gen_g4_IonScintPDExt.root_262726_141_1_20251215T213518Z_PDInt.root_268923_7_1_20251217T210448Z_detsim_reco1.root"
17-Dec-2025 21:58:19 UTC Closed input file "temp_detsim.root"
=====================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
=====================================================================================================================================
Full event 48.2848 52.811 68.0105 51.6343 5.497 10
-------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000784665 0.0023136 0.00383714 0.00213607 0.00099059 10
reco:wclsdatahd:WireCellToolkit 32.7447 34.0064 35.8136 33.794 0.803882 10
reco:wirefilter:WireFilter 0.00849986 0.00994869 0.0131686 0.00912288 0.00166023 10
reco:gaushit:GausHitFinder 0.228581 0.296898 0.487111 0.282007 0.0685981 10
reco:nhitsfilter:NumberOfHitsFilter 0.000133386 0.000203018 0.000486473 0.000173993 9.82156e-05 10
reco:reco3d:SpacePointSolver 0.0947268 0.254647 0.83029 0.198286 0.206119 10
reco:hitpdune:DisambigFromSpacePoints 0.0849872 0.176061 0.420876 0.136714 0.0912188 10
reco:pandora:StandardPandora 2.11099 3.07353 6.6732 2.57206 1.26891 10
reco:pandoraWriter:StandardPandora 0.0636719 0.0928758 0.14523 0.0888901 0.0235925 10
reco:pandoraTrack:LArPandoraTrackCreation 0.237102 0.356787 0.787328 0.318707 0.15102 10
reco:pandoraShower:LArPandoraShowerCreation 0.168978 0.316272 0.778722 0.275352 0.161537 10
reco:pandoracalo:Calorimetry 0.155823 0.221332 0.455194 0.196836 0.0823615 10
reco:pandoracalonosce:Calorimetry 0.151769 0.216444 0.45486 0.190173 0.0833604 10
reco:pandoraShowercalo:ShowerCalorimetry 0.158501 0.316395 0.774248 0.285577 0.164982 10
reco:pandoraShowercalonosce:ShowerCalorimetry 0.156574 0.310548 0.766599 0.276764 0.163475 10
reco:pandoraGnocchiCalo:GnocchiCalorimetry 0.0191508 0.0251071 0.036217 0.0249605 0.00479286 10
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0186421 0.0235473 0.0382892 0.0219422 0.00547043 10
reco:emtrkmichelid:EmTrackMichelId 9.84709 12.4271 19.0964 11.8188 2.48562 10
[art]:TriggerResults:TriggerResultInserter 1.535e-05 2.88775e-05 0.000108528 2.1937e-05 2.67587e-05 10
end_path:out1:RootOutput 4.959e-06 9.454e-06 3.6921e-05 5.766e-06 9.296e-06 10
end_path:out1:RootOutput(write) 0.540469 0.659151 0.919779 0.63322 0.103146 10
=====================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5584.98 MB
Peak resident set size usage (VmHWM): 3431.16 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 10850904
-rw-r--r-- 1 dunegli fnalgrid 231871 Dec 17 21:58 jobscript.log
-rw-r--r-- 1 dunegli fnalgrid 201 Dec 17 21:58 justin-processed-pfns.txt
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 17 21:58 reco_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 3574 Dec 17 21:58 Pandora_Geometry.xml
-rw-r--r-- 1 dunegli fnalgrid 139264 Dec 17 21:58 mem.db
-rw-r--r-- 1 dunegli fnalgrid 28672 Dec 17 21:58 time.db
-rw-r--r-- 1 dunegli fnalgrid 5621643727 Dec 17 21:58 pdhd_prod_beam__267379_97_1_20251212T162205Z_gen_g4_IonScintPDExt.root_262726_141_1_20251215T213518Z_PDInt.root_268923_7_1_20251217T210448Z_detsim_reco1.root
-rw-r--r-- 1 dunegli fnalgrid 28645450 Dec 17 21:58 Pandora_Events.pndr
-rw-r--r-- 1 dunegli fnalgrid 0 Dec 17 21:48 debugprod.log
-rw-r--r-- 1 dunegli fnalgrid 519 Dec 17 21:48 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dunegli fnalgrid 5460512240 Dec 17 21:48 temp_detsim.root
-rw-r--r-- 1 dunegli fnalgrid 122 Dec 17 21:04 all-input-dids.txt
