Jobsub ID 236601.109@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
at call=45
[13:25:23.056] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[13:25:23.056] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[13:25:23.056] D [ glue ] frame sink sees EOS
[13:25:23.056] D [ pgraph ] <Pgrapher:> graph execution complete
[13:25:23.056] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.55 sec
[13:25:23.056] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.39 sec
[13:25:23.056] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.09 sec
[13:25:23.056] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.09 sec
[13:25:23.056] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.03 sec
[13:25:23.056] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.94 sec
[13:25:23.056] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.7 sec
[13:25:23.056] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.54 sec
[13:25:23.056] I [ timer ] Timer: WireCell::Aux::Resampler : 0.82 sec
[13:25:23.057] I [ timer ] Timer: WireCell::Aux::Resampler : 0.82 sec
[13:25:23.057] I [ timer ] Timer: WireCell::Aux::Resampler : 0.81 sec
[13:25:23.057] I [ timer ] Timer: WireCell::Aux::Resampler : 0.81 sec
[13:25:23.057] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[13:25:23.057] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[13:25:23.057] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:25:23.057] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:25:23.057] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:25:23.057] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:25:23.057] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:25:23.057] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:25:23.057] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:25:23.057] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:25:23.057] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:25:23.057] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:25:23.057] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:25:23.057] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:25:23.057] I [ timer ] Timer: Total node execution : 99.60999991372228 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 41044 traces tagged "gauss"
FrameSaver: q=8.23242e+06 n=829207 tag=gauss
wclsFrameSaver: saving 50431 traces tagged "wiener"
FrameSaver: q=8.74231e+06 n=790354 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 230 XUs and 514 XVs -> 11 XUVs
C:0 T:1 4206 XUs and 2994 XVs -> 225 XUVs
C:0 T:2 362 XUs and 312 XVs -> 9 XUVs
C:0 T:3 2071 XUs and 1881 XVs -> 72 XUVs
C:0 T:4 315 XUs and 334 XVs -> 9 XUVs
C:0 T:5 273 XUs and 412 XVs -> 22 XUVs
C:0 T:6 447 XUs and 624 XVs -> 24 XUVs
C:0 T:7 218 XUs and 288 XVs -> 7 XUVs
C:0 T:8 650 XUs and 600 XVs -> 32 XUVs
C:0 T:9 1077 XUs and 982 XVs -> 59 XUVs
C:0 T:10 883 XUs and 661 XVs -> 57 XUVs
C:0 T:11 1409 XUs and 1170 XVs -> 67 XUVs
C:0 T:12 578 XUs and 537 XVs -> 30 XUVs
C:0 T:13 404 XUs and 554 XVs -> 27 XUVs
C:0 T:14 853 XUs and 1076 XVs -> 45 XUVs
C:0 T:15 853 XUs and 678 XVs -> 31 XUVs
727 XUVs total
637 collection wire objects
727 potential space points
Neighbour search...
5467 tests to find 2342 neighbours
Iterating with no regularization...
Begin: 7.38006e+08
0 7.32109e+08
1 7.31964e+08
Now with regularization...
Begin: 7.26562e+08
0 7.26541e+08
03-Nov-2025 13:25:52 CET Closed output file "np02vd_raw_run040346_3321_df-s04-d2_dw_0_20251103T103502_reco_stage1_20251103T122552_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 134.005 220.793 585.514 207.962 85.8377 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.0517e-05 8.17493e-05 0.000186696 7.7998e-05 2.76193e-05 23
produce:tpcrawdecoder:PDVDTPCReader 42.534 74.3143 131.322 76.5316 21.3313 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.535311 0.663794 1.33738 0.613142 0.176571 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000264173 0.000302622 0.000700565 0.000280074 8.62834e-05 23
produce:ophit:OpHitFinder 5.1067e-05 7.66817e-05 0.000521615 5.5325e-05 9.49912e-05 23
produce:opflash:OpFlashFinderVerticalDrift 3.4967e-05 5.09418e-05 0.000318456 3.8584e-05 5.70967e-05 23
produce:wclsdatavd:WireCellToolkit 46.5233 91.1043 138.743 89.7637 19.6461 23
produce:gaushit:GausHitFinder 0.758156 1.04878 2.26299 0.966084 0.306873 23
produce:nhitsfilter:NumberOfHitsFilter 0.000180173 0.00029713 0.000590325 0.000290984 8.59812e-05 23
produce:reco3d:SpacePointSolver 6.28048 10.3808 33.2917 8.66326 5.55987 23
produce:hitpdune:DisambigFromSpacePoints 0.0904706 0.158565 0.58566 0.12289 0.106916 23
produce:pandora:StandardPandora 12.2041 38.0199 325.162 19.1065 63.0833 23
produce:pandoraTrack:LArPandoraTrackCreation 0.378544 1.15631 7.81734 0.704505 1.51942 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0186746 0.0278787 0.0558947 0.0259895 0.00757614 23
[art]:TriggerResults:TriggerResultInserter 1.587e-05 2.51477e-05 5.0377e-05 2.2443e-05 9.28676e-06 23
end_path:out1:RootOutput 4.207e-06 5.45022e-06 2.0358e-05 4.849e-06 3.19703e-06 23
end_path:out1:RootOutput(write) 3.3972 3.86339 4.68659 3.73499 0.371075 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5972.08 MB
Peak resident set size usage (VmHWM): 3949.13 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3321_df-s04-d2_dw_0_20251103T103502_reco_stage1_20251103T122552_keepup.root
\tHists: np02vd_raw_run040346_3321_df-s04-d2_dw_0_20251103T103502_reco_stage1_20251103T122553_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3321_df-s04-d2_dw_0_20251103T103502_reco_stage1_20251103T122552_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3321_df-s04-d2_dw_0_20251103T103502_reco_stage1_20251103T122552_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_00d00",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1762172753.0,
"core.end_time": 1762172753.0,
"core.events": [
306594,
306598,
306602,
306606,
306610,
306614,
306618,
306622,
306626,
306630,
306634,
306638,
306642,
306646,
306650,
306654,
306658,
306662,
306666,
306670,
306674,
306678,
306682
],
"core.event_count": 23,
"core.first_event_number": 306594,
"core.last_event_number": 306682,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3321_df-s04-d2_dw_0_20251103T103502.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3321_df-s04-d2_dw_0_20251103T103502_reco_stage1_20251103T122553_keepup_hists.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "root",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_00d00",
"core.data_tier": "root-tuple-virtual",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1762172753.0,
"core.end_time": 1762172753.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3321_df-s04-d2_dw_0_20251103T103502.hdf5"
}
]
} 