Jobsub ID 269964.1@justin-prod-sched01.dune.hep.ac.uk
| Jobsub ID | 269964.1@justin-prod-sched01.dune.hep.ac.uk | |
| Workflow ID | 3576 | |
| 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-10-05 09:17:59 | |
| Site | US_Colorado | |
| Entry | CMSHTPC_T3_US_Colorado_heposg01-colorado | |
| Last heartbeat | 2024-10-05 11:54:33 | |
| From worker node | Hostname | lnxfarm300.colorado.edu |
| cpuinfo | Intel(R) Xeon(R) CPU E5-2650 v3 @ 2.30GHz | |
| OS release | Scientific Linux release 7.9 (Nitrogen) | |
| Processors | 1 | |
| RSS bytes | 4193255424 (3999 MiB) | |
| Wall seconds limit | 171000 (47 hours) | |
| Inner Apptainer? | True | |
| Job state | finished | |
| Allocator name | justin-allocator-pro.dune.hep.ac.uk | |
| Started | 2024-10-05 09:18:55 | |
| Input files | hd-protodune:np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555.hdf5 | |
| Jobscript | Exit code | 0 |
| Real time | 2h (9276s) | |
| CPU time | 2h (7951s = 85%) | |
| Outputting started | 2024-10-05 11:53:32 | |
| Output files | hd-protodune-det-reco:np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555_reco_stage1_reco_stage2_20241005T115315_keepup.root hd-protodune-det-reco:np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555_reco_stage1_reco_stage2_20241005T115315_keepup_hists.root | |
| Finished | 2024-10-05 11:54:33 | |
| Saved logs | justin-logs:269964.1-justin-prod-sched01.dune.hep.ac.uk.logs.tgz | |
| List job events Wrapper job log | ||
Jobscript log (last 10,000 characters)
FrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 37498 traces tagged "gauss"
FrameSaver: q=1.38295e+06 n=680918 tag=gauss
wclsFrameSaver: saving 48869 traces tagged "wiener"
FrameSaver: q=1.49401e+06 n=624492 tag=wiener
3642 hits in TPC 0
4397 hits in TPC 1
5129 hits in TPC 2
3033 hits in TPC 3
2080 hits in TPC 4
3047 hits in TPC 5
3686 hits in TPC 6
2856 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 11511 XUs and 6054 XVs -> 248 XUVs
C:0 T:2 10999 XUs and 9168 XVs -> 623 XUVs
C:0 T:3 182 XUs and 220 XVs -> 16 XUVs
C:0 T:4 7 XUs and 3 XVs -> 0 XUVs
C:0 T:5 9025 XUs and 11405 XVs -> 623 XUVs
C:0 T:6 11999 XUs and 10052 XVs -> 909 XUVs
C:0 T:7 518 XUs and 277 XVs -> 9 XUVs
2428 XUVs total
1375 collection wire objects
2428 potential space points
Neighbour search...
46572 tests to find 27552 neighbours
Iterating with no regularization...
Begin: 3.10043e+07
0 2.94249e+07
1 2.93194e+07
2 2.93108e+07
Now with regularization...
Begin: 2.73719e+07
0 2.73699e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------
05-Oct-2024 05:53:15 MDT Closed output file "np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555_reco_stage1_reco_stage2_20241005T115315_keepup.root"
05-Oct-2024 05:53:15 MDT Closed input file "np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555_reco_stage1.root"
========================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
========================================================================================================================================
Full event 107.501 167.524 258.385 152.986 38.1318 40
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000791642 0.00303841 0.0054118 0.00280987 0.00123448 40
produce:fembfilter:PDHDFEMBFilter 0.789357 0.954429 1.20385 0.836587 0.172002 40
produce:wclsdatahd:WireCellToolkit 52.2225 71.0415 85.8727 73.1092 7.2157 40
produce:gaushit:GausHitFinder 0.603188 1.10455 1.96152 1.05743 0.29835 40
produce:nhitsfilter:NumberOfHitsFilter 0.000282878 0.000469491 0.000837898 0.000425933 0.00012012 40
produce:reco3d:SpacePointSolver 0.249074 3.22666 26.4715 1.07489 5.07759 40
produce:hitpdune:DisambigFromSpacePoints 0.274119 1.08789 2.98757 0.858722 0.677896 40
produce:pandora:StandardPandora 6.89409 24.7472 52.5449 20.0025 11.9074 40
produce:pandoraWriter:StandardPandora 0.114811 0.216563 0.453817 0.198739 0.0659582 40
produce:pandoraTrack:LArPandoraTrackCreation 1.36291 3.22749 6.7647 3.03382 1.30809 40
produce:pandoraShower:LArPandoraShowerCreation 1.06381 4.06593 8.42395 3.7278 2.0007 40
produce:pandoracalo:Calorimetry 0.50523 1.43336 3.18815 1.23243 0.644245 40
produce:pandoracalonosce:Calorimetry 0.458035 1.35955 2.95623 1.24055 0.597508 40
produce:pandorapid:Chi2ParticleID 0.0016649 0.00259724 0.00483878 0.00243455 0.000702949 40
produce:pandoraShowercalo:ShowerCalorimetry 0.854319 3.38778 7.54437 2.79335 1.80415 40
produce:pandoraShowercalonosce:ShowerCalorimetry 0.828569 3.35315 9.12563 2.70655 1.85986 40
produce:pandoraGnocchiCalo:GnocchiCalorimetry 0.0995557 0.18025 0.371141 0.161221 0.0556757 40
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.0325189 0.0651943 0.146049 0.0593589 0.0223268 40
produce:emtrkmichelid:EmTrackMichelId 22.6119 45.1564 77.5841 41.0108 12.5399 40
[art]:TriggerResults:TriggerResultInserter 2.3424e-05 5.269e-05 0.000111877 5.5107e-05 1.60393e-05 40
end_path:out1:RootOutput 8.916e-06 1.50614e-05 3.3388e-05 1.36225e-05 4.91327e-06 40
end_path:michelremoving:michelremoving 0.0249204 0.156776 0.71923 0.0746636 0.163365 40
end_path:michelremovinggnocchi:michelremoving 0.0236143 0.153027 0.915683 0.071837 0.178741 40
end_path:calibanagnocchi:CalibAnaTree 0.397478 1.27791 3.36884 1.15205 0.661649 40
end_path:out1:RootOutput(write) 0.611164 1.16296 2.26132 1.02686 0.408558 40
========================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5048.25 MB
Peak resident set size usage (VmHWM): 2704.75 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555_reco_stage1_reco_stage2_20241005T115315_keepup.root
\tHists: np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555_reco_stage1_reco_stage2_20241005T115315_keepup_hists.root
Forming reco metadata
Successfully opened file np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555_reco_stage1_reco_stage2_20241005T115315_keepup.root
Ran successfully
{
"name": "np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555_reco_stage1_reco_stage2_20241005T115315_keepup.root",
"namespace": "hd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v09_91_02d01",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage2_calibration_protodunehd_keepup.fcl",
"dune.campaign": "hd-protodune-reco-keepup-v0",
"core.start_time": 1728122434.0,
"core.end_time": 1728129196.0,
"core.events": [
4841,
4842,
4843,
4844,
4845,
4846,
4847,
4848,
4849,
4850,
4851,
4852,
4853,
4854,
4855,
4856,
4857,
4858,
4859,
4860,
4861,
4862,
4863,
4864,
4865,
4866,
4867,
4868,
4869,
4870,
4871,
4872,
4873,
4874,
4875,
4876,
4877,
4878,
4879,
4880
],
"core.event_count": 40,
"core.first_event_number": 4841,
"core.last_event_number": 4880,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "hd-protodune",
"core.runs": [
27555
],
"core.runs_subruns": [
2755500001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics",
"origin.applications.config_files": {
"reco1": "standard_reco_stage1_protodunehd_keepup.fcl"
},
"origin.applications.versions": {
"reco1": "v09_91_02d01"
},
"origin.applications.names": [
"reco1"
]
},
"parents": [
{
"did": "hd-protodune:np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555_reco_stage1_reco_stage2_20241005T115315_keepup_hists.root",
"namespace": "hd-protodune-det-reco",
"metadata": {
"core.file_format": "root",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v09_91_02d01",
"core.data_tier": "root-tuple-virtual",
"dune.config_file": "standard_reco_stage2_calibration_protodunehd_keepup.fcl",
"dune.campaign": "hd-protodune-reco-keepup-v0",
"core.start_time": 1728122434.0,
"core.end_time": 1728129196.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "hd-protodune",
"core.runs": [
27555
],
"core.runs_subruns": [
2755500001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics",
"origin.applications.config_files": {
"reco1": "standard_reco_stage1_protodunehd_keepup.fcl"
},
"origin.applications.versions": {
"reco1": "v09_91_02d01"
},
"origin.applications.names": [
"reco1"
]
},
"parents": [
{
"did": "hd-protodune:np04hd_raw_run027555_0121_dataflow0_datawriter_0_20240630T044555.hdf5"
}
]
}