Jobsub ID 236756.4@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
OS
[19:07:28.430] D [ pgraph ] <Pgrapher:> graph execution complete
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.3 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.28 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.7 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.62 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.4 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.32 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.27 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.66 sec
[19:07:28.431] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[19:07:28.431] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[19:07:28.431] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[19:07:28.431] I [ timer ] Timer: WireCell::Aux::Resampler : 0.21 sec
[19:07:28.431] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:07:28.431] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:07:28.431] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[19:07:28.431] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[19:07:28.431] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[19:07:28.431] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[19:07:28.431] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[19:07:28.431] I [ timer ] Timer: Total node execution : 53.600000163540244 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 40356 traces tagged "gauss"
FrameSaver: q=1.02784e+07 n=879708 tag=gauss
wclsFrameSaver: saving 50307 traces tagged "wiener"
FrameSaver: q=1.11799e+07 n=845692 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 51 XUs and 48 XVs -> 1 XUVs
C:0 T:1 1272 XUs and 1407 XVs -> 40 XUVs
C:0 T:2 974 XUs and 991 XVs -> 34 XUVs
C:0 T:3 430 XUs and 442 XVs -> 13 XUVs
C:0 T:4 84 XUs and 128 XVs -> 2 XUVs
C:0 T:5 18813 XUs and 19594 XVs -> 1230 XUVs
C:0 T:6 811 XUs and 648 XVs -> 22 XUVs
C:0 T:7 324 XUs and 507 XVs -> 22 XUVs
C:0 T:8 630 XUs and 558 XVs -> 24 XUVs
C:0 T:9 955 XUs and 635 XVs -> 31 XUVs
C:0 T:10 544 XUs and 590 XVs -> 50 XUVs
C:0 T:11 2749 XUs and 2936 XVs -> 183 XUVs
C:0 T:12 234 XUs and 233 XVs -> 7 XUVs
C:0 T:13 545 XUs and 745 XVs -> 22 XUVs
C:0 T:14 1133 XUs and 931 XVs -> 56 XUVs
C:0 T:15 818 XUs and 1156 XVs -> 42 XUVs
1779 XUVs total
1113 collection wire objects
1779 potential space points
Neighbour search...
52745 tests to find 12612 neighbours
Iterating with no regularization...
Begin: 4.78514e+09
0 4.41035e+09
1 4.39642e+09
2 4.3961e+09
Now with regularization...
Begin: 4.3275e+09
0 4.32735e+09
PcaShowerParticleBuildingAlgorithm::OpeningAngle - principal eigenvalue less than or equal to 0.
TestBeamParticleCreationAlgorithm::SetupTestBeamVertex - Test beam particle has no initial vertex
03-Nov-2025 19:08:02 CET Closed output file "np02vd_raw_run040346_3672_df-s04-d2_dw_0_20251103T154717_reco_stage1_20251103T180802_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 73.3179 196.836 1541.19 101.307 318.92 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.915e-05 0.000122637 0.000251892 0.000103837 4.11847e-05 23
produce:tpcrawdecoder:PDVDTPCReader 5.49646 6.25574 8.14047 5.96482 0.704285 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.0828839 0.0972722 0.182642 0.0908227 0.020469 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000272884 0.000461534 0.00152899 0.000386967 0.000253226 23
produce:ophit:OpHitFinder 7.289e-05 0.000149756 0.000742496 0.000123177 0.00012938 23
produce:opflash:OpFlashFinderVerticalDrift 4.6961e-05 0.000106039 0.000453828 7.0868e-05 9.86179e-05 23
produce:wclsdatavd:WireCellToolkit 48.7709 71.1831 248.071 55.8588 43.6693 23
produce:gaushit:GausHitFinder 0.687479 1.34258 4.21516 1.21565 0.691714 23
produce:nhitsfilter:NumberOfHitsFilter 0.000173633 0.000384537 0.000782105 0.000326194 0.000159591 23
produce:reco3d:SpacePointSolver 6.05353 12.9348 44.8691 9.72589 9.27032 23
produce:hitpdune:DisambigFromSpacePoints 0.0750637 0.216666 1.001 0.147958 0.201644 23
produce:pandora:StandardPandora 7.07498 97.9227 1221.35 22.3286 263.84 23
produce:pandoraTrack:LArPandoraTrackCreation 0.419031 1.84001 10.1083 0.948727 2.46527 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0153551 0.0379336 0.0944954 0.0308785 0.0183706 23
[art]:TriggerResults:TriggerResultInserter 2.6781e-05 7.96951e-05 0.00034992 6.5709e-05 6.45531e-05 23
end_path:out1:RootOutput 7.452e-06 1.5998e-05 5.9951e-05 1.4742e-05 1.01029e-05 23
end_path:out1:RootOutput(write) 3.61745 4.90195 7.35463 4.75993 0.767169 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 11080.5 MB
Peak resident set size usage (VmHWM): 8993.41 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3672_df-s04-d2_dw_0_20251103T154717_reco_stage1_20251103T180802_keepup.root
\tHists: np02vd_raw_run040346_3672_df-s04-d2_dw_0_20251103T154717_reco_stage1_20251103T180803_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3672_df-s04-d2_dw_0_20251103T154717_reco_stage1_20251103T180802_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3672_df-s04-d2_dw_0_20251103T154717_reco_stage1_20251103T180802_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": 1762193284.0,
"core.end_time": 1762193284.0,
"core.events": [
339014,
339018,
339022,
339026,
339030,
339034,
339038,
339042,
339046,
339050,
339054,
339058,
339062,
339066,
339070,
339074,
339078,
339082,
339086,
339090,
339094,
339098,
339102
],
"core.event_count": 23,
"core.first_event_number": 339014,
"core.last_event_number": 339102,
"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_3672_df-s04-d2_dw_0_20251103T154717.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3672_df-s04-d2_dw_0_20251103T154717_reco_stage1_20251103T180803_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": 1762193284.0,
"core.end_time": 1762193284.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_3672_df-s04-d2_dw_0_20251103T154717.hdf5"
}
]
} 