Jobsub ID 269271.53@justin-prod-sched01.dune.hep.ac.uk
| Jobsub ID | 269271.53@justin-prod-sched01.dune.hep.ac.uk | |
| Workflow ID | 3571 | |
| 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-04 16:39:58 | |
| Site | US_FNAL-T1 | |
| Entry | CMSHTPC_T1_US_FNAL_condce_opp1_whole | |
| Last heartbeat | 2024-10-04 21:27:35 | |
| From worker node | Hostname | dunegli-35534-0-cmswn4053.fnal.gov |
| cpuinfo | AMD EPYC 7543 32-Core Processor | |
| 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-04 16:55:12 | |
| Input files | hd-protodune:np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250.hdf5 | |
| Jobscript | Exit code | 0 |
| Real time | 4h (16298s) | |
| CPU time | 2h (8401s = 51%) | |
| Outputting started | 2024-10-04 21:26:51 | |
| Output files | hd-protodune-det-reco:np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250_reco_stage1_reco_stage2_20241004T212645_keepup.root hd-protodune-det-reco:np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250_reco_stage1_reco_stage2_20241004T212645_keepup_hists.root | |
| Finished | 2024-10-04 21:27:35 | |
| Saved logs | justin-logs:269271.53-justin-prod-sched01.dune.hep.ac.uk.logs.tgz | |
| List job events Wrapper job log | ||
Jobscript log (last 10,000 characters)
articleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------
Begin processing the 121st record. run: 27541 subRun: 1 event: 121 at 04-Oct-2024 21:26:44 UTC
Begin processing the 122nd record. run: 27541 subRun: 1 event: 122 at 04-Oct-2024 21:26:45 UTC
04-Oct-2024 21:26:45 UTC Closed output file "np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250_reco_stage1_reco_stage2_20241004T212645_keepup.root"
04-Oct-2024 21:26:45 UTC Closed input file "np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250_reco_stage1.root"
========================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
========================================================================================================================================
Full event 0.00996171 63.0195 660.692 0.569069 101.499 122
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 9.9449e-05 0.000821074 0.00697823 0.000332245 0.00122829 122
produce:fembfilter:PDHDFEMBFilter 2.4156e-05 0.190281 0.711208 0.0632058 0.239435 122
produce:wclsdatahd:WireCellToolkit 21.0413 44.028 85.0685 23.7746 25.5405 50
produce:gaushit:GausHitFinder 0.156931 4.4359 10.9361 0.386046 4.86989 50
produce:nhitsfilter:NumberOfHitsFilter 0.000150285 0.00117851 0.00413367 0.000317963 0.00111329 50
produce:reco3d:SpacePointSolver 0.0108693 0.0202114 0.0294908 0.0197882 0.00558663 29
produce:hitpdune:DisambigFromSpacePoints 0.0155932 0.0474931 0.187024 0.0384541 0.0349172 29
produce:pandora:StandardPandora 29.9924 140.343 580.11 105.216 109.144 29
produce:pandoraWriter:StandardPandora 0.0917932 0.243609 0.449536 0.223624 0.0852404 29
produce:pandoraTrack:LArPandoraTrackCreation 0.00424925 0.0374312 0.203302 0.0268664 0.0385517 29
produce:pandoraShower:LArPandoraShowerCreation 0.000133453 0.00697554 0.0604387 0.00135318 0.0125169 29
produce:pandoracalo:Calorimetry 0.000439664 0.0179912 0.153471 0.00728352 0.0316617 29
produce:pandoracalonosce:Calorimetry 0.000173168 0.0151294 0.139416 0.00535897 0.0288114 29
produce:pandorapid:Chi2ParticleID 8.8027e-05 0.000326375 0.000955901 0.000284018 0.000208055 29
produce:pandoraShowercalo:ShowerCalorimetry 6.3661e-05 0.00559654 0.0606832 0.000969407 0.0116875 29
produce:pandoraShowercalonosce:ShowerCalorimetry 3.8142e-05 0.00531726 0.0615994 0.000921797 0.0117912 29
produce:pandoraGnocchiCalo:GnocchiCalorimetry 0.000213835 0.00279586 0.0122635 0.00237607 0.00281245 29
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry 0.000107053 0.00161768 0.00801915 0.00104225 0.00179927 29
produce:emtrkmichelid:EmTrackMichelId 20.2847 37.702 59.6572 39.0139 9.45968 29
[art]:TriggerResults:TriggerResultInserter 8.807e-06 4.03011e-05 9.3608e-05 4.2105e-05 2.08108e-05 122
end_path:out1:RootOutput 1.273e-06 8.2624e-06 3.158e-05 7.1985e-06 5.97818e-06 122
end_path:michelremoving:michelremoving 5.47e-06 0.00122283 0.0138733 3.03875e-05 0.00251298 122
end_path:michelremovinggnocchi:michelremoving 3.286e-06 0.000941038 0.00868811 8.266e-06 0.00191867 122
end_path:calibanagnocchi:CalibAnaTree 3.236e-06 0.00343727 0.0644444 8.015e-06 0.00852328 122
end_path:out1:RootOutput(write) 0.00018486 0.421526 2.23453 0.00066212 0.768458 122
========================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5643.1 MB
Peak resident set size usage (VmHWM): 3262.94 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250_reco_stage1_reco_stage2_20241004T212645_keepup.root
\tHists: np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250_reco_stage1_reco_stage2_20241004T212645_keepup_hists.root
Forming reco metadata
Successfully opened file np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250_reco_stage1_reco_stage2_20241004T212645_keepup.root
Ran successfully
{
"name": "np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250_reco_stage1_reco_stage2_20241004T212645_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": 1728069490.0,
"core.end_time": 1728077205.0,
"core.events": [
1,
2,
3,
4,
5,
6,
7,
8,
9,
10,
11,
12,
13,
14,
15,
16,
17,
18,
19,
20,
21,
22,
23,
24,
25,
26,
27,
28,
29,
30,
31,
32,
33,
34,
35,
36,
37,
38,
39,
40,
41,
42,
43,
44,
45,
46,
47,
48,
49,
50,
51,
52,
53,
54,
55,
56,
57,
58,
59,
60,
61,
62,
63,
64,
65,
66,
67,
68,
69,
70,
71,
72,
73,
74,
75,
76,
77,
78,
79,
80,
81,
82,
83,
84,
85,
86,
87,
88,
89,
90,
91,
92,
93,
94,
95,
96,
97,
98,
99,
100,
101,
102,
103,
104,
105,
106,
107,
108,
109,
110,
111,
112,
113,
114,
115,
116,
117,
118,
119,
120,
121,
122
],
"core.event_count": 122,
"core.first_event_number": 1,
"core.last_event_number": 122,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "hd-protodune",
"core.runs": [
27541
],
"core.runs_subruns": [
2754100001
],
"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_run027541_0000_dataflow0_datawriter_0_20240628T092250.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np04hd_raw_run027541_0000_dataflow0_datawriter_0_20240628T092250_reco_stage1_reco_stage2_20241004T212645_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": 1728069490.0,
"core.end_time": 1728077205.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "hd-protodune",
"core.runs": [
27541
],
"core.runs_subruns": [
2754100001
],
"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_run027541_0000_dataflow0_datawriter_0_20240628T092250.hdf5"
}
]
}