Jobsub ID 168731.0@justin-prod-sched02.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
95] Moving MsgHandler: 0x92e9870 (message: kXR_read (handle: 0x00000000, offset: 4199855600, size: 512) ) from out-queu to in-queue.
[2025-04-02 10:45:54.666929 +0100][Debug ][ExDbgMsg ][ 1147] [msg: 0x942a460] Assigned MsgHandler: 0x92e9870.
[2025-04-02 10:45:54.666952 +0100][Debug ][ExDbgMsg ][ 1147] [handler: 0x92e9870] Removed MsgHandler: 0x92e9870 from the in-queue.
[2025-04-02 10:45:54.666996 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] Calling MsgHandler: 0x92e9870 (message: kXR_read (handle: 0x00000000, offset: 4199855600, size: 512) ) with status: [SUCCESS] .
[2025-04-02 10:45:54.667026 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] Destroying MsgHandler: 0x92e9870.
[2025-04-02 10:45:54.667211 +0100][Debug ][File ][ 1147] [0x842aef0@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/1c/40/np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251.hdf5?xrdcl.requuid=74a120f7-95fb-4a40-b32d-bc08f1f6c64c] Sending a read command for handle 0x0 to st-096-ff8090f2.cern.ch:1095
[2025-04-02 10:45:54.667242 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] MsgHandler created: 0x92e9ab0 (message: kXR_read (handle: 0x00000000, offset: 4224100528, size: 1101672) ).
[2025-04-02 10:45:54.667430 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] Moving MsgHandler: 0x92e9ab0 (message: kXR_read (handle: 0x00000000, offset: 4224100528, size: 1101672) ) from out-queu to in-queue.
[2025-04-02 10:45:54.713152 +0100][Debug ][ExDbgMsg ][ 1147] [msg: 0x92bd640] Assigned MsgHandler: 0x92e9ab0.
[2025-04-02 10:45:54.713180 +0100][Debug ][ExDbgMsg ][ 1147] [handler: 0x92e9ab0] Removed MsgHandler: 0x92e9ab0 from the in-queue.
[2025-04-02 10:45:55.170235 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] Calling MsgHandler: 0x92e9ab0 (message: kXR_read (handle: 0x00000000, offset: 4224100528, size: 1101672) ) with status: [SUCCESS] .
[2025-04-02 10:45:55.170277 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] Destroying MsgHandler: 0x92e9ab0.
02-Apr-2025 10:45:58 BST Closed output file "np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251_reco_stage1_20250402T094558_keepup.root"
[2025-04-02 10:45:58.314968 +0100][Debug ][File ][ 1147] [0x842aef0@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/1c/40/np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251.hdf5?xrdcl.requuid=74a120f7-95fb-4a40-b32d-bc08f1f6c64c] Sending a close command for handle 0x0 to st-096-ff8090f2.cern.ch:1095
[2025-04-02 10:45:58.315153 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] MsgHandler created: 0xa9f8d20 (message: kXR_close (handle: 0x00000000) ).
[2025-04-02 10:45:58.315245 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] Moving MsgHandler: 0xa9f8d20 (message: kXR_close (handle: 0x00000000) ) from out-queu to in-queue.
[2025-04-02 10:45:58.344449 +0100][Debug ][ExDbgMsg ][ 1147] [msg: 0x8e44700] Assigned MsgHandler: 0xa9f8d20.
[2025-04-02 10:45:58.344489 +0100][Debug ][ExDbgMsg ][ 1147] [handler: 0xa9f8d20] Removed MsgHandler: 0xa9f8d20 from the in-queue.
[2025-04-02 10:45:58.344529 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] Calling MsgHandler: 0xa9f8d20 (message: kXR_close (handle: 0x00000000) ) with status: [SUCCESS] .
[2025-04-02 10:45:58.344557 +0100][Debug ][File ][ 1147] [0x842aef0@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/1c/40/np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251.hdf5?xrdcl.requuid=74a120f7-95fb-4a40-b32d-bc08f1f6c64c] Close returned from st-096-ff8090f2.cern.ch:1095 with: [SUCCESS]
[2025-04-02 10:45:58.344578 +0100][Debug ][ExDbgMsg ][ 1147] [st-096-ff8090f2.cern.ch:1095] Destroying MsgHandler: 0xa9f8d20.
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 49.4421 75.9747 114.344 72.8499 17.5301 44
--------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.578e-06 1.95929e-05 7.2934e-05 1.84585e-05 1.07767e-05 44
produce:tpcrawdecoder:PDVDTPCReader 47.5266 74.0043 112.316 70.8683 17.5014 44
[art]:TriggerResults:TriggerResultInserter 1.0623e-05 1.54714e-05 4.0287e-05 1.49345e-05 4.94243e-06 44
end_path:out1:RootOutput 2.472e-06 4.46141e-06 1.2669e-05 4.5765e-06 1.64429e-06 44
end_path:out1:RootOutput(write) 1.89966 1.97007 2.35047 1.95174 0.0733902 44
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 1638.38 MB
Peak resident set size usage (VmHWM): 908.554 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
[2025-04-02 10:46:00.022045 +0100][Debug ][JobMgr ][ 1147] Stopping the job manager...
[2025-04-02 10:46:00.022357 +0100][Debug ][JobMgr ][ 1147] Job manager stopped
[2025-04-02 10:46:00.022371 +0100][Debug ][TaskMgr ][ 1147] Stopping the task manager...
[2025-04-02 10:46:00.022480 +0100][Debug ][TaskMgr ][ 1147] Task manager stopped
[2025-04-02 10:46:00.022514 +0100][Debug ][Poller ][ 1147] Stopping the poller...
[2025-04-02 10:46:00.022595 +0100][Debug ][AsyncSock ][ 1147] [st-096-ff8090f2.cern.ch:1095.0] Closing the socket
[2025-04-02 10:46:00.022712 +0100][Debug ][Poller ][ 1147] <[::ffff:192.41.105.40]:52740><--><[::ffff:128.142.52.204]:1095> Removing socket from the poller
[2025-04-02 10:46:00.022881 +0100][Debug ][PostMaster ][ 1147] [st-096-ff8090f2.cern.ch:1095] Destroying stream
[2025-04-02 10:46:00.022900 +0100][Debug ][AsyncSock ][ 1147] [st-096-ff8090f2.cern.ch:1095.0] Closing the socket
Output files:
\tReco: np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251_reco_stage1_20250402T094558_keepup.root
\tHists: np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251_reco_stage1_20250402T094559_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251_reco_stage1_20250402T094558_keepup.root
Ran successfully
{
"name": "np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251_reco_stage1_20250402T094558_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_03_01d01",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1743587160.0,
"core.end_time": 1743587160.0,
"core.events": [
7701,
7702,
7703,
7704,
7705,
7706,
7707,
7708,
7709,
7710,
7711,
7712,
7713,
7714,
7715,
7716,
7717,
7718,
7719,
7720,
7721,
7722,
7723,
7724,
7725,
7726,
7727,
7728,
7729,
7730,
7731,
7732,
7733,
7734,
7735,
7736,
7737,
7738,
7739,
7740,
7741,
7742,
7743,
7744
],
"core.event_count": 44,
"core.first_event_number": 7701,
"core.last_event_number": 7744,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
35707
],
"core.runs_subruns": [
3570700001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251_reco_stage1_20250402T094559_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_03_01d01",
"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": 1743587160.0,
"core.end_time": 1743587160.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
35707
],
"core.runs_subruns": [
3570700001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run035707_0175_df-s04-d0_dw_0_20250402T072251.hdf5"
}
]
} 