Jobsub ID 360079.0@justin-prod-sched01.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
95] Moving MsgHandler: 0xa524b20 (message: kXR_read (handle: 0x00000000, offset: 4218658048, size: 512) ) from out-queu to in-queue.
[2025-04-02 02:27:37.675746 +0100][Debug ][ExDbgMsg ][ 1145] [msg: 0x9270bf0] Assigned MsgHandler: 0xa524b20.
[2025-04-02 02:27:37.675774 +0100][Debug ][ExDbgMsg ][ 1145] [handler: 0xa524b20] Removed MsgHandler: 0xa524b20 from the in-queue.
[2025-04-02 02:27:37.675804 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] Calling MsgHandler: 0xa524b20 (message: kXR_read (handle: 0x00000000, offset: 4218658048, size: 512) ) with status: [SUCCESS] .
[2025-04-02 02:27:37.675820 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] Destroying MsgHandler: 0xa524b20.
[2025-04-02 02:27:37.676000 +0100][Debug ][File ][ 1145] [0x7a090b0@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/ef/0c/np02vd_raw_run035707_0106_df-s04-d0_dw_0_20250401T225644.hdf5?xrdcl.requuid=b304c842-68aa-48c1-8258-6d448864a6c9] Sending a read command for handle 0x0 to st-096-hh151b16.cern.ch:1095
[2025-04-02 02:27:37.676027 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] MsgHandler created: 0x9238150 (message: kXR_read (handle: 0x00000000, offset: 4269685536, size: 1108872) ).
[2025-04-02 02:27:37.676090 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] Moving MsgHandler: 0x9238150 (message: kXR_read (handle: 0x00000000, offset: 4269685536, size: 1108872) ) from out-queu to in-queue.
[2025-04-02 02:27:37.703700 +0100][Debug ][ExDbgMsg ][ 1145] [msg: 0xbff2540] Assigned MsgHandler: 0x9238150.
[2025-04-02 02:27:37.703714 +0100][Debug ][ExDbgMsg ][ 1145] [handler: 0x9238150] Removed MsgHandler: 0x9238150 from the in-queue.
[2025-04-02 02:27:38.322993 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] Calling MsgHandler: 0x9238150 (message: kXR_read (handle: 0x00000000, offset: 4269685536, size: 1108872) ) with status: [SUCCESS] .
[2025-04-02 02:27:38.323028 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] Destroying MsgHandler: 0x9238150.
02-Apr-2025 02:27:43 BST Closed output file "np02vd_raw_run035707_0106_df-s04-d0_dw_0_20250401T225644_reco_stage1_20250402T012743_keepup.root"
[2025-04-02 02:27:43.858385 +0100][Debug ][File ][ 1145] [0x7a090b0@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/ef/0c/np02vd_raw_run035707_0106_df-s04-d0_dw_0_20250401T225644.hdf5?xrdcl.requuid=b304c842-68aa-48c1-8258-6d448864a6c9] Sending a close command for handle 0x0 to st-096-hh151b16.cern.ch:1095
[2025-04-02 02:27:43.858632 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] MsgHandler created: 0xc78e070 (message: kXR_close (handle: 0x00000000) ).
[2025-04-02 02:27:43.859767 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] Moving MsgHandler: 0xc78e070 (message: kXR_close (handle: 0x00000000) ) from out-queu to in-queue.
[2025-04-02 02:27:43.888818 +0100][Debug ][ExDbgMsg ][ 1145] [msg: 0xacb4d50] Assigned MsgHandler: 0xc78e070.
[2025-04-02 02:27:43.888853 +0100][Debug ][ExDbgMsg ][ 1145] [handler: 0xc78e070] Removed MsgHandler: 0xc78e070 from the in-queue.
[2025-04-02 02:27:43.888886 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] Calling MsgHandler: 0xc78e070 (message: kXR_close (handle: 0x00000000) ) with status: [SUCCESS] .
[2025-04-02 02:27:43.888919 +0100][Debug ][File ][ 1145] [0x7a090b0@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/ef/0c/np02vd_raw_run035707_0106_df-s04-d0_dw_0_20250401T225644.hdf5?xrdcl.requuid=b304c842-68aa-48c1-8258-6d448864a6c9] Close returned from st-096-hh151b16.cern.ch:1095 with: [SUCCESS]
[2025-04-02 02:27:43.888948 +0100][Debug ][ExDbgMsg ][ 1145] [st-096-hh151b16.cern.ch:1095] Destroying MsgHandler: 0xc78e070.
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 68.6064 98.615 152.713 93.3765 19.4833 44
--------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.985e-06 1.28144e-05 7.5434e-05 1.09925e-05 9.61173e-06 44
produce:tpcrawdecoder:PDVDTPCReader 66.578 96.6145 150.735 91.4099 19.4714 44
[art]:TriggerResults:TriggerResultInserter 1.0933e-05 1.65909e-05 4.324e-05 1.2691e-05 8.18361e-06 44
end_path:out1:RootOutput 2.473e-06 4.37352e-06 2.4402e-05 2.77e-06 4.62595e-06 44
end_path:out1:RootOutput(write) 1.92095 2.00017 2.84044 1.96948 0.138916 44
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 1638.03 MB
Peak resident set size usage (VmHWM): 909.402 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
[2025-04-02 02:27:58.540769 +0100][Debug ][JobMgr ][ 1145] Stopping the job manager...
[2025-04-02 02:27:58.541123 +0100][Debug ][JobMgr ][ 1145] Job manager stopped
[2025-04-02 02:27:58.541144 +0100][Debug ][TaskMgr ][ 1145] Stopping the task manager...
[2025-04-02 02:27:58.541296 +0100][Debug ][TaskMgr ][ 1145] Task manager stopped
[2025-04-02 02:27:58.541323 +0100][Debug ][Poller ][ 1145] Stopping the poller...
[2025-04-02 02:27:58.541476 +0100][Debug ][AsyncSock ][ 1145] [st-096-hh151b16.cern.ch:1095.0] Closing the socket
[2025-04-02 02:27:58.541527 +0100][Debug ][Poller ][ 1145] <[::ffff:192.41.105.39]:48198><--><[::ffff:128.142.128.29]:1095> Removing socket from the poller
[2025-04-02 02:27:58.541565 +0100][Debug ][PostMaster ][ 1145] [st-096-hh151b16.cern.ch:1095] Destroying stream
[2025-04-02 02:27:58.541583 +0100][Debug ][AsyncSock ][ 1145] [st-096-hh151b16.cern.ch:1095.0] Closing the socket
Output files:
\tReco: np02vd_raw_run035707_0106_df-s04-d0_dw_0_20250401T225644_reco_stage1_20250402T012743_keepup.root
\tHists: np02vd_raw_run035707_0106_df-s04-d0_dw_0_20250401T225644_reco_stage1_20250402T012758_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run035707_0106_df-s04-d0_dw_0_20250401T225644_reco_stage1_20250402T012743_keepup.root
Ran successfully
{
"name": "np02vd_raw_run035707_0106_df-s04-d0_dw_0_20250401T225644_reco_stage1_20250402T012743_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": 1743557278.0,
"core.end_time": 1743557278.0,
"core.events": [
4665,
4666,
4667,
4668,
4669,
4670,
4671,
4672,
4673,
4674,
4675,
4676,
4677,
4678,
4679,
4680,
4681,
4682,
4683,
4684,
4685,
4686,
4687,
4688,
4689,
4690,
4691,
4692,
4693,
4694,
4695,
4696,
4697,
4698,
4699,
4700,
4701,
4702,
4703,
4704,
4705,
4706,
4707,
4708
],
"core.event_count": 44,
"core.first_event_number": 4665,
"core.last_event_number": 4708,
"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_0106_df-s04-d0_dw_0_20250401T225644.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run035707_0106_df-s04-d0_dw_0_20250401T225644_reco_stage1_20250402T012758_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": 1743557278.0,
"core.end_time": 1743557278.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_0106_df-s04-d0_dw_0_20250401T225644.hdf5"
}
]
} 