justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 80671.4@justin-prod-sched02.dune.hep.ac.uk

Jobsub ID80671.4@justin-prod-sched02.dune.hep.ac.uk
Workflow ID3531
Stage ID1
User namecalcuttj@fnal.gov
HTCondor Groupgroup_dune.prod_mcsim
RequestedProcessors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit18000 (5 hours)
Submitted time2024-09-28 01:15:13
SiteUK_Edinburgh
EntryDUNE_UK_SGridECDF_ce1_multicore
Last heartbeat2024-09-28 01:31:34
From worker nodeHostnamenode2a05.ecdf.ed.ac.uk
cpuinfoIntel(R) Xeon(R) Gold 6130 CPU @ 2.10GHz
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit171000 (47 hours)
Inner Apptainer?True
Job statefinished
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-28 01:16:59
Input fileshd-protodune:np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518.hdf5
JobscriptExit code0
Real time13m (807s)
CPU time5m (330s = 40%)
Outputting started2024-09-28 01:30:26
Output fileshd-protodune-det-reco:np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518_reco_stage1_reco_stage2_20240928T013016_keepup.root
hd-protodune-det-reco:np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518_reco_stage1_reco_stage2_20240928T013016_keepup_hists.root
Finished2024-09-28 01:31:34
Saved logsjustin-logs:80671.4-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

d. run: 29382 subRun: 1 event: 2264 at 28-Sep-2024 02:29:49 BST
28-Sep-2024 02:29:50 BST  Opened output file with pattern "%ifb_reco_stage2_%tc_keepup.root"
Begin processing the 2nd record. run: 29382 subRun: 1 event: 2266 at 28-Sep-2024 02:29:51 BST
Begin processing the 3rd record. run: 29382 subRun: 1 event: 2268 at 28-Sep-2024 02:29:51 BST
Begin processing the 4th record. run: 29382 subRun: 1 event: 2270 at 28-Sep-2024 02:29:52 BST
Begin processing the 5th record. run: 29382 subRun: 1 event: 2272 at 28-Sep-2024 02:29:53 BST
Begin processing the 6th record. run: 29382 subRun: 1 event: 2274 at 28-Sep-2024 02:29:53 BST
Begin processing the 7th record. run: 29382 subRun: 1 event: 2276 at 28-Sep-2024 02:29:54 BST
Begin processing the 8th record. run: 29382 subRun: 1 event: 2278 at 28-Sep-2024 02:29:55 BST
Begin processing the 9th record. run: 29382 subRun: 1 event: 2280 at 28-Sep-2024 02:29:55 BST
Begin processing the 10th record. run: 29382 subRun: 1 event: 2282 at 28-Sep-2024 02:29:56 BST
Begin processing the 11th record. run: 29382 subRun: 1 event: 2284 at 28-Sep-2024 02:29:57 BST
Begin processing the 12th record. run: 29382 subRun: 1 event: 2286 at 28-Sep-2024 02:29:57 BST
Begin processing the 13th record. run: 29382 subRun: 1 event: 2288 at 28-Sep-2024 02:29:58 BST
Begin processing the 14th record. run: 29382 subRun: 1 event: 2290 at 28-Sep-2024 02:29:58 BST
Begin processing the 15th record. run: 29382 subRun: 1 event: 2292 at 28-Sep-2024 02:29:59 BST
Begin processing the 16th record. run: 29382 subRun: 1 event: 2294 at 28-Sep-2024 02:30:00 BST
Begin processing the 17th record. run: 29382 subRun: 1 event: 2296 at 28-Sep-2024 02:30:00 BST
Begin processing the 18th record. run: 29382 subRun: 1 event: 2298 at 28-Sep-2024 02:30:01 BST
Begin processing the 19th record. run: 29382 subRun: 1 event: 2300 at 28-Sep-2024 02:30:02 BST
Begin processing the 20th record. run: 29382 subRun: 1 event: 2302 at 28-Sep-2024 02:30:02 BST
Begin processing the 21st record. run: 29382 subRun: 1 event: 2304 at 28-Sep-2024 02:30:03 BST
Begin processing the 22nd record. run: 29382 subRun: 1 event: 2306 at 28-Sep-2024 02:30:04 BST
Begin processing the 23rd record. run: 29382 subRun: 1 event: 2308 at 28-Sep-2024 02:30:04 BST
Begin processing the 24th record. run: 29382 subRun: 1 event: 2310 at 28-Sep-2024 02:30:05 BST
Begin processing the 25th record. run: 29382 subRun: 1 event: 2312 at 28-Sep-2024 02:30:06 BST
Begin processing the 26th record. run: 29382 subRun: 1 event: 2314 at 28-Sep-2024 02:30:06 BST
Begin processing the 27th record. run: 29382 subRun: 1 event: 2316 at 28-Sep-2024 02:30:07 BST
Begin processing the 28th record. run: 29382 subRun: 1 event: 2318 at 28-Sep-2024 02:30:08 BST
Begin processing the 29th record. run: 29382 subRun: 1 event: 2320 at 28-Sep-2024 02:30:08 BST
Begin processing the 30th record. run: 29382 subRun: 1 event: 2322 at 28-Sep-2024 02:30:09 BST
Begin processing the 31st record. run: 29382 subRun: 1 event: 2324 at 28-Sep-2024 02:30:10 BST
Begin processing the 32nd record. run: 29382 subRun: 1 event: 2326 at 28-Sep-2024 02:30:10 BST
Begin processing the 33rd record. run: 29382 subRun: 1 event: 2328 at 28-Sep-2024 02:30:11 BST
Begin processing the 34th record. run: 29382 subRun: 1 event: 2330 at 28-Sep-2024 02:30:11 BST
Begin processing the 35th record. run: 29382 subRun: 1 event: 2332 at 28-Sep-2024 02:30:12 BST
Begin processing the 36th record. run: 29382 subRun: 1 event: 2334 at 28-Sep-2024 02:30:13 BST
Begin processing the 37th record. run: 29382 subRun: 1 event: 2336 at 28-Sep-2024 02:30:13 BST
Begin processing the 38th record. run: 29382 subRun: 1 event: 2338 at 28-Sep-2024 02:30:14 BST
Begin processing the 39th record. run: 29382 subRun: 1 event: 2340 at 28-Sep-2024 02:30:15 BST
28-Sep-2024 02:30:16 BST  Closed output file "np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518_reco_stage1_reco_stage2_20240928T013016_keepup.root"
28-Sep-2024 02:30:16 BST  Closed input file "np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518_reco_stage1.root"

===================================================================================================================================
TimeTracker printout (sec)                           Min           Avg           Max         Median          RMS         nEvts   
===================================================================================================================================
Full event                                        0.618156      0.647559      0.748035      0.643247      0.0246961       39     
-----------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                           0.00017935    0.000214771   0.000728954   0.000197897   8.56103e-05      39     
produce:fembfilter:PDHDFEMBFilter                 0.616797       0.64621      0.741728      0.642142      0.024118        39     
[art]:TriggerResults:TriggerResultInserter       2.6797e-05    3.16317e-05   8.8179e-05    2.9371e-05    1.00787e-05      39     
end_path:out1:RootOutput                          2.761e-06    3.61626e-06   1.4752e-05     3.097e-06    1.94271e-06      39     
end_path:michelremoving:michelremoving            9.92e-06     1.64586e-05   0.000204415   1.0955e-05    3.05623e-05      39     
end_path:michelremovinggnocchi:michelremoving     3.302e-06    5.71079e-06    7.38e-05      3.63e-06     1.11441e-05      39     
end_path:calibanagnocchi:CalibAnaTree             2.852e-06    5.42662e-06   7.0363e-05     3.186e-06    1.08916e-05      39     
end_path:out1:RootOutput(write)                  0.000510428   0.000736661   0.00448958    0.000531681   0.000678255      39     
===================================================================================================================================

====================================================================================================
MemoryTracker summary (base-10 MB units used)

  Peak virtual memory usage (VmPeak)  : 3637.35 MB
  Peak resident set size usage (VmHWM): 1290.67 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518_reco_stage1_reco_stage2_20240928T013016_keepup.root
\tHists: np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518_reco_stage1_reco_stage2_20240928T013016_keepup_hists.root
Forming reco metadata
Successfully opened file np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518_reco_stage1_reco_stage2_20240928T013016_keepup.root
Ran successfully
{
  "name": "np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518_reco_stage1_reco_stage2_20240928T013016_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": 1727486956.0,
    "core.end_time": 1727487017.0,
    "core.events": [
      2264,
      2266,
      2268,
      2270,
      2272,
      2274,
      2276,
      2278,
      2280,
      2282,
      2284,
      2286,
      2288,
      2290,
      2292,
      2294,
      2296,
      2298,
      2300,
      2302,
      2304,
      2306,
      2308,
      2310,
      2312,
      2314,
      2316,
      2318,
      2320,
      2322,
      2324,
      2326,
      2328,
      2330,
      2332,
      2334,
      2336,
      2338,
      2340
    ],
    "core.event_count": 39,
    "core.first_event_number": 2264,
    "core.last_event_number": 2340,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "hd-protodune",
    "core.runs": [
      29382
    ],
    "core.runs_subruns": [
      2938200001
    ],
    "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_run029382_0029_dataflow0_datawriter_0_20240928T004518.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np04hd_raw_run029382_0029_dataflow0_datawriter_0_20240928T004518_reco_stage1_reco_stage2_20240928T013016_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": 1727486956.0,
    "core.end_time": 1727487017.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "hd-protodune",
    "core.runs": [
      29382
    ],
    "core.runs_subruns": [
      2938200001
    ],
    "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_run029382_0029_dataflow0_datawriter_0_20240928T004518.hdf5"
    }
  ]
}
justIN time: 2024-09-29 09:26:10 UTC       justIN version: 01.01.08