Jobsub ID 263865.5@justin-prod-sched01.dune.hep.ac.uk
| Jobsub ID | 263865.5@justin-prod-sched01.dune.hep.ac.uk |
| Workflow ID | 3448 |
| Stage ID | 1 |
| User name | calcuttj@fnal.gov |
| HTCondor Group | group_dune.prod_mcsim |
| Requested | Processors | 1 |
| RSS bytes | 4193255424 (3999 MiB) |
| Wall seconds limit | 18000 (5 hours) |
| Submitted time | 2024-09-26 13:56:43 |
| Site | IT_CNAF |
| Entry | CMSHTPC_T1_IT_CNAF_condor_ce02 |
| Last heartbeat | 2024-09-26 15:49:25 |
| From worker node | Hostname | cn-314-04-07.cr.cnaf.infn.it |
| cpuinfo | Intel(R) Xeon(R) CPU E5-2697 v4 @ 2.30GHz |
| OS release | Scientific Linux release 7.9 (Nitrogen) |
| Processors | 1 |
| RSS bytes | 4193255424 (3999 MiB) |
| Wall seconds limit | 216000 (60 hours) |
| Inner Apptainer? | True |
| Job state | jobscript_error |
| Allocator name | justin-allocator-pro.dune.hep.ac.uk |
| Started | 2024-09-26 13:57:51 |
| Input files | hd-protodune:np04hd_raw_run029094_0094_dataflow4_datawriter_0_20240906T091908.hdf5
|
| Jobscript | Exit code | 1 |
| Real time | 0m (0s) |
| CPU time | 0m (0s = 0%) |
| Outputting started | |
| Output files | |
| Finished | 2024-09-26 15:49:25 |
| Saved logs | justin-logs:263865.5-justin-prod-sched01.dune.hep.ac.uk.logs.tgz |
| List job events Wrapper job log |
Jobscript log (last 10,000 characters)
ation...
Begin: 1.20854e+07
0 1.05009e+07
1 1.03599e+07
2 1.03337e+07
3 1.03239e+07
Now with regularization...
Begin: 9.7946e+06
0 9.78857e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------
Begin processing the 29th record. run: 29094 subRun: 1 event: 22788 at 26-Sep-2024 17:43:42 CEST
RawFrameSource: got 10240 raw::RawDigit objects
input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 48200 traces, 48200 summary
Retagger: tagging trace set: gauss with 36193 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 36193 traces tagged "gauss"
FrameSaver: q=925169 n=657922 tag=gauss
wclsFrameSaver: saving 48200 traces tagged "wiener"
FrameSaver: q=1.00176e+06 n=594786 tag=wiener
1558 hits in TPC 0
2145 hits in TPC 1
2116 hits in TPC 2
1005 hits in TPC 3
2932 hits in TPC 4
6251 hits in TPC 5
6032 hits in TPC 6
3950 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 1173 XUs and 177 XVs -> 8 XUVs
C:0 T:2 5912 XUs and 5256 XVs -> 94 XUVs
C:0 T:3 27 XUs and 34 XVs -> 5 XUVs
C:0 T:4 258 XUs and 165 XVs -> 4 XUVs
C:0 T:5 20759 XUs and 16525 XVs -> 764 XUVs
C:0 T:6 52835 XUs and 19622 XVs -> 725 XUVs
C:0 T:7 165 XUs and 205 XVs -> 8 XUVs
1608 XUVs total
642 collection wire objects
1608 potential space points
Neighbour search...
48570 tests to find 26426 neighbours
Iterating with no regularization...
Begin: 3.1731e+07
0 3.03504e+07
1 3.02642e+07
2 3.02598e+07
Now with regularization...
Begin: 2.95262e+07
0 2.95218e+07
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------
no of hits closeby 0 event 22788 TrkackID 2 startx, y, z -138.987 583.417 197.867 wireno, peakt tpcno 454 1323.36 6 dist 0min T, max_T84.1629 2140.87
no of hits closeby 0 event 22788 TrkackID 2 startx, y, z -138.987 583.417 197.867 wireno, peakt tpcno 454 1323.36 6 dist 0min T, max_T84.1629 2140.87
Begin processing the 30th record. run: 29094 subRun: 1 event: 22804 at 26-Sep-2024 17:46:06 CEST
RawFrameSource: got 10240 raw::RawDigit objects
input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 58134 traces, 58134 summary
Retagger: tagging trace set: gauss with 44374 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 44374 traces tagged "gauss"
FrameSaver: q=995487 n=878120 tag=gauss
wclsFrameSaver: saving 58134 traces tagged "wiener"
FrameSaver: q=1.06704e+06 n=805895 tag=wiener
4388 hits in TPC 0
5078 hits in TPC 1
5655 hits in TPC 2
3543 hits in TPC 3
1572 hits in TPC 4
2403 hits in TPC 5
5188 hits in TPC 6
3618 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 4 XUs and 0 XVs -> 0 XUVs
C:0 T:1 967 XUs and 972 XVs -> 41 XUVs
C:0 T:2 10292 XUs and 11575 XVs -> 422 XUVs
C:0 T:3 491 XUs and 473 XVs -> 27 XUVs
C:0 T:4 2 XUs and 5 XVs -> 0 XUVs
C:0 T:5 4383 XUs and 10975 XVs -> 203 XUVs
C:0 T:6 13040 XUs and 17635 XVs -> 579 XUVs
C:0 T:7 222 XUs and 333 XVs -> 6 XUVs
1278 XUVs total
833 collection wire objects
1278 potential space points
Neighbour search...
12208 tests to find 7172 neighbours
Iterating with no regularization...
Begin: 5.50491e+06
0 5.36088e+06
1 5.35747e+06
Now with regularization...
Begin: 4.95299e+06
0 4.95271e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------
no of hits closeby 0 event 22804 TrkackID 2 startx, y, z 258.694 571.336 364.268 wireno, peakt tpcno 379 3274.51 2 dist 21.8884min T, max_T3.5 3298.37
no of hits closeby 0 event 22804 TrkackID 2 startx, y, z 258.694 571.336 364.268 wireno, peakt tpcno 379 3274.51 2 dist 21.8884min T, max_T3.5 3298.37
26-Sep-2024 17:48:54 CEST Closed output file "np04hd_raw_run029094_0094_dataflow4_datawriter_0_20240906T091908_reco_stage1_reco_stage2_20240926T154854_keepup.root"
26-Sep-2024 17:48:54 CEST Closed input file "np04hd_raw_run029094_0094_dataflow4_datawriter_0_20240906T091908_reco_stage1.root"
========================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
========================================================================================================================================
Full event 124.17 163.548 254.856 159.868 28.7219 30
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000544591 0.00288154 0.00473453 0.00293632 0.00114652 30
produce:fembfilter:PDHDFEMBFilter 0.841761 1.08005 1.33507 1.07153 0.0670556 30
produce:wclsdatahd:WireCellToolkit 73.9855 76.3901 80.2042 76.1412 1.47556 30
produce:gaushit:GausHitFinder 0.592018 1.08374 1.4811 1.10525 0.236258 30
produce:nhitsfilter:NumberOfHitsFilter 0.00034055 0.000496058 0.000835492 0.000497301 9.97101e-05 30
produce:reco3d:SpacePointSolver 0.250638 1.07356 4.17423 0.772803 0.87701 30
produce:hitpdune:DisambigFromSpacePoints 0.240137 0.869062 1.93669 0.727539 0.460749 30
produce:pandora:StandardPandora 7.7424 20.2273 71.1532 18.0078 12.5428 30
produce:pandoraWriter:StandardPandora 0.140981 0.230609 0.360352 0.225246 0.0506646 30
produce:pandoraTrack:LArPandoraTrackCreation 1.0881 2.43014 4.17406 2.40924 0.807339 30
produce:pandoraShower:LArPandoraShowerCreation 0.892064 2.7853 4.78618 2.58858 1.17432 30
produce:pandoracalo:Calorimetry 0.546154 1.16032 2.05588 1.13414 0.371427 30
produce:pandoracalonosce:Calorimetry 0.477108 1.06925 1.79713 1.04361 0.352684 30
produce:pandorapid:Chi2ParticleID 0.0019412 0.00296536 0.00582056 0.00286192 0.000708742 30
produce:pandoraShowercalo:ShowerCalorimetry 0.869075 2.55695 4.12185 2.41643 1.00514 30
produce:pandoraShowercalonosce:ShowerCalorimetry 0.888928 2.4996 4.04569 2.35186 0.968579 30
produce:pandoraGnocchiCalo:GnocchiCalorimetry 0.146539 0.215718 0.302401 0.206219 0.0414894 30
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0588659 0.0854056 0.119333 0.080806 0.0166076 30
produce:emtrkmichelid:EmTrackMichelId 31.5206 47.4429 78.2675 44.1471 11.0141 30
[art]:TriggerResults:TriggerResultInserter 3.7642e-05 5.94871e-05 0.000105334 5.84245e-05 1.52277e-05 30
end_path:out1:RootOutput 1.0062e-05 1.43595e-05 3.1442e-05 1.1972e-05 5.52792e-06 30
end_path:michelremoving:michelremoving 0.0383354 0.112111 0.322115 0.0647917 0.087386 30
end_path:michelremovinggnocchi:michelremoving 0.0372087 0.107227 0.308582 0.0608138 0.0856255 30
end_path:calibanagnocchi:CalibAnaTree 0.428229 0.822487 1.55354 0.738584 0.302001 30
end_path:out1:RootOutput(write) 0.74947 1.15476 1.64364 1.09121 0.253238 30
========================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4925.28 MB
Peak resident set size usage (VmHWM): 2316.23 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029094_0094_dataflow4_datawriter_0_20240906T091908_reco_stage1_reco_stage2_20240926T154854_keepup.root
\tHists: np04hd_raw_run029094_0094_dataflow4_datawriter_0_20240906T091908_reco_stage1_reco_stage2_20240926T154854_keepup_hists.root
Forming reco metadata
/cvmfs/larsoft.opensciencegrid.org/products/python/v3_9_15/Linux64bit+3.10-2.17/bin/python: No module named meta_maker
Error in reco metadata
