Jobsub ID 401001.0@justin-prod-sched01.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
25-05-20 10:20:52.564151 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] Calling MsgHandler: 0xd1f19b0 (message: kXR_read (handle: 0x00000000, offset: 4186461744, size: 512) ) with status: [SUCCESS] .
[2025-05-20 10:20:52.564187 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] Destroying MsgHandler: 0xd1f19b0.
[2025-05-20 10:20:52.564305 +0100][Debug ][File ][ 1146] [0x704b2b0@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/00/15/np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041.hdf5?xrdcl.requuid=a6d56d14-0290-4a17-a3c0-e9ecaae2eb2f] Sending a read command for handle 0x0 to st-096-hh151b08.cern.ch:1095
[2025-05-20 10:20:52.564347 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] MsgHandler created: 0xd1f19b0 (message: kXR_read (handle: 0x00000000, offset: 4187566984, size: 128) ).
[2025-05-20 10:20:52.564409 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] Moving MsgHandler: 0xd1f19b0 (message: kXR_read (handle: 0x00000000, offset: 4187566984, size: 128) ) from out-queu to in-queue.
[2025-05-20 10:20:52.591498 +0100][Debug ][ExDbgMsg ][ 1146] [msg: 0x7595750] Assigned MsgHandler: 0xd1f19b0.
[2025-05-20 10:20:52.591539 +0100][Debug ][ExDbgMsg ][ 1146] [handler: 0xd1f19b0] Removed MsgHandler: 0xd1f19b0 from the in-queue.
[2025-05-20 10:20:52.591577 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] Calling MsgHandler: 0xd1f19b0 (message: kXR_read (handle: 0x00000000, offset: 4187566984, size: 128) ) with status: [SUCCESS] .
[2025-05-20 10:20:52.591726 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] Destroying MsgHandler: 0xd1f19b0.
20-May-2025 10:20:56 BST Closed output file "np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041_reco_stage1_20250520T092056_keepup.root"
[2025-05-20 10:20:56.127244 +0100][Debug ][File ][ 1146] [0x704b2b0@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/00/15/np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041.hdf5?xrdcl.requuid=a6d56d14-0290-4a17-a3c0-e9ecaae2eb2f] Sending a close command for handle 0x0 to st-096-hh151b08.cern.ch:1095
[2025-05-20 10:20:56.127472 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] MsgHandler created: 0x9bd1bb0 (message: kXR_close (handle: 0x00000000) ).
[2025-05-20 10:20:56.127776 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] Moving MsgHandler: 0x9bd1bb0 (message: kXR_close (handle: 0x00000000) ) from out-queu to in-queue.
[2025-05-20 10:20:56.155901 +0100][Debug ][ExDbgMsg ][ 1146] [msg: 0x8affef0] Assigned MsgHandler: 0x9bd1bb0.
[2025-05-20 10:20:56.155956 +0100][Debug ][ExDbgMsg ][ 1146] [handler: 0x9bd1bb0] Removed MsgHandler: 0x9bd1bb0 from the in-queue.
[2025-05-20 10:20:56.156015 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] Calling MsgHandler: 0x9bd1bb0 (message: kXR_close (handle: 0x00000000) ) with status: [SUCCESS] .
[2025-05-20 10:20:56.156052 +0100][Debug ][File ][ 1146] [0x704b2b0@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/00/15/np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041.hdf5?xrdcl.requuid=a6d56d14-0290-4a17-a3c0-e9ecaae2eb2f] Close returned from st-096-hh151b08.cern.ch:1095 with: [SUCCESS]
[2025-05-20 10:20:56.156091 +0100][Debug ][ExDbgMsg ][ 1146] [st-096-hh151b08.cern.ch:1095] Destroying MsgHandler: 0x9bd1bb0.
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 25.8184 49.8277 75.6446 52.7051 12.3639 44
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 1.7111e-05 2.20631e-05 9.5244e-05 1.9404e-05 1.15568e-05 44
produce:tpcrawdecoder:PDVDTPCReader 23.4428 47.436 73.2676 50.2931 12.3629 44
produce:triggerrawdecoder:PDVDTriggerReader4 0.46313 0.468381 0.519299 0.464477 0.0109278 44
produce:pdhddaphne:DAPHNEReaderPDHD 0.0002816 0.000298564 0.000498572 0.00029342 3.09367e-05 44
produce:ophit:OpHitFinder 7.6899e-05 0.000106584 0.000631056 8.3594e-05 8.35695e-05 44
[art]:TriggerResults:TriggerResultInserter 9.141e-06 1.0588e-05 3.6432e-05 9.8535e-06 3.98417e-06 44
end_path:out1:RootOutput 2.48e-06 3.42802e-06 1.2807e-05 3.0305e-06 1.55371e-06 44
end_path:out1:RootOutput(write) 1.88577 1.92243 2.03535 1.91741 0.0239851 44
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 1670.74 MB
Peak resident set size usage (VmHWM): 915.997 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
[2025-05-20 10:20:57.008756 +0100][Debug ][JobMgr ][ 1146] Stopping the job manager...
[2025-05-20 10:20:57.009104 +0100][Debug ][JobMgr ][ 1146] Job manager stopped
[2025-05-20 10:20:57.009234 +0100][Debug ][TaskMgr ][ 1146] Stopping the task manager...
[2025-05-20 10:20:57.009327 +0100][Debug ][TaskMgr ][ 1146] Task manager stopped
[2025-05-20 10:20:57.009344 +0100][Debug ][Poller ][ 1146] Stopping the poller...
[2025-05-20 10:20:57.009661 +0100][Debug ][AsyncSock ][ 1146] [st-096-hh151b08.cern.ch:1095.0] Closing the socket
[2025-05-20 10:20:57.009882 +0100][Debug ][Poller ][ 1146] <[::ffff:192.41.105.40]:41052><--><[::ffff:128.142.128.82]:1095> Removing socket from the poller
[2025-05-20 10:20:57.009965 +0100][Debug ][PostMaster ][ 1146] [st-096-hh151b08.cern.ch:1095] Destroying stream
[2025-05-20 10:20:57.009977 +0100][Debug ][AsyncSock ][ 1146] [st-096-hh151b08.cern.ch:1095.0] Closing the socket
Output files:
\tReco: np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041_reco_stage1_20250520T092056_keepup.root
\tHists: np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041_reco_stage1_20250520T092056_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041_reco_stage1_20250520T092056_keepup.root
Ran successfully
{
"name": "np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041_reco_stage1_20250520T092056_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_06_00d01",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1747732857.0,
"core.end_time": 1747732857.0,
"core.events": [
4048,
4049,
4050,
4051,
4052,
4053,
4054,
4055,
4056,
4057,
4058,
4059,
4060,
4061,
4062,
4063,
4064,
4065,
4066,
4067,
4068,
4069,
4070,
4071,
4072,
4073,
4074,
4075,
4076,
4077,
4078,
4079,
4080,
4081,
4082,
4083,
4084,
4085,
4086,
4087,
4088,
4089,
4090,
4091
],
"core.event_count": 44,
"core.first_event_number": 4048,
"core.last_event_number": 4091,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
36585
],
"core.runs_subruns": [
3658500001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041_reco_stage1_20250520T092056_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_06_00d01",
"core.data_tier": "root-tuple-virtual",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1747732857.0,
"core.end_time": 1747732857.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
36585
],
"core.runs_subruns": [
3658500001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run036585_0092_df-s04-d0_dw_0_20250520T060041.hdf5"
}
]
} 