Jobsub ID 294228.11@dunegpschedd02.fnal.gov
| Jobsub ID | 294228.11@dunegpschedd02.fnal.gov |
| Workflow ID | 12303 |
| Stage ID | 1 |
| User name | ykermaid@fnal.gov |
| Requested | Processors | 1 |
| GPU | No |
| RSS bytes | 4194304000 (4000 MiB) |
| Wall seconds limit | 18000 (5 hours) |
| Submitted time | 2026-01-23 10:42:09 |
| Site | UK_Edinburgh |
| Entry | DUNE_UK_SGridECDF_ce1_multicore |
| Last heartbeat | 2026-01-23 11:46:59 |
| From worker node | Hostname | node2b04.ecdf.ed.ac.uk |
| cpuinfo | Intel(R) Xeon(R) Gold 6338 CPU @ 2.00GHz |
| OS release | Scientific Linux release 7.9 (Nitrogen) |
| Processors | 1 |
| RSS bytes | 4194304000 (4000 MiB) |
| Wall seconds limit | 171000 (47 hours) |
| GPU | |
| Inner Apptainer? | True |
| Job state | finished |
| Started | 2026-01-23 10:43:23 |
| Input files | vd-protodune:np02vd_raw_run040346_0323_df-s04-d0_dw_0_20251101T162245.hdf5
|
| Jobscript | Exit code | 0 |
| Real time | 1h (3707s) |
| CPU time | 54m (3278s = 88%) |
| Max RSS bytes | 3449602048 (3289 MiB) |
| Outputting started | 2026-01-23 11:45:12 |
| Output files | |
| Finished | 2026-01-23 11:46:59 |
| Saved logs | justin-logs:294228.11-dunegpschedd02.fnal.gov.logs.tgz |
| List job events Cached HTCondor job logs |
Jobscript log (last 10,000 characters)
usNoiseFilter : 2.3 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.3 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.09 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.04 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.04 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::OmnibusNoiseFilter : 2.01 sec
[11:44:58.967] I [ timer ] Timer: WireCell::Aux::Resampler : 0.2 sec
[11:44:58.967] I [ timer ] Timer: WireCell::Aux::Resampler : 0.2 sec
[11:44:58.967] I [ timer ] Timer: WireCell::Aux::Resampler : 0.2 sec
[11:44:58.967] I [ timer ] Timer: WireCell::Aux::Resampler : 0.2 sec
[11:44:58.967] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:44:58.967] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:44:58.967] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:44:58.967] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:44:58.967] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:44:58.967] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:44:58.967] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:44:58.967] I [ timer ] Timer: Total node execution : 108.01999915204942 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 12288 traces tagged "gauss"
FrameSaver: q=0 n=0 tag=gauss
wclsFrameSaver: saving 12288 traces tagged "wiener"
FrameSaver: q=0 n=0 tag=wiener
==> # merged: 0, in 1 outside loops
++++>>>> total num hits: 0, num free: 0
23-Jan-2026 11:45:03 GMT Closed output file "np02vd_raw_run040346_0323_df-s04-d0_dw_0_20251101T162245_reco_stage0_20260123T114503_offline.root"
[2026-01-23 11:45:03.990797 +0000][Debug ][File ][ 1143] [0xb43d470@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/82/0e/np02vd_raw_run040346_0323_df-s04-d0_dw_0_20251101T162245.hdf5?xrdcl.requuid=cb45ecd2-2a69-4b86-81c6-fdcf0b6a91a1] Sending a close command for handle 0x0 to st-096-gg5612cq.cern.ch:1095
[2026-01-23 11:45:03.993400 +0000][Debug ][ExDbgMsg ][ 1143] [st-096-gg5612cq.cern.ch:1095] MsgHandler created: 0x17a831b0 (message: kXR_close (handle: 0x00000000) ).
[2026-01-23 11:45:03.993850 +0000][Debug ][ExDbgMsg ][ 1143] [st-096-gg5612cq.cern.ch:1095] Moving MsgHandler: 0x17a831b0 (message: kXR_close (handle: 0x00000000) ) from out-queu to in-queue.
[2026-01-23 11:45:04.022458 +0000][Debug ][ExDbgMsg ][ 1143] [msg: 0x5a95b330] Assigned MsgHandler: 0x17a831b0.
[2026-01-23 11:45:04.022622 +0000][Debug ][ExDbgMsg ][ 1143] [handler: 0x17a831b0] Removed MsgHandler: 0x17a831b0 from the in-queue.
[2026-01-23 11:45:04.023581 +0000][Debug ][ExDbgMsg ][ 1143] [st-096-gg5612cq.cern.ch:1095] Calling MsgHandler: 0x17a831b0 (message: kXR_close (handle: 0x00000000) ) with status: [SUCCESS] .
[2026-01-23 11:45:04.024383 +0000][Debug ][File ][ 1143] [0xb43d470@root://eospublic.cern.ch:1094//eos/experiment/neutplatform/protodune/dune/vd-protodune/82/0e/np02vd_raw_run040346_0323_df-s04-d0_dw_0_20251101T162245.hdf5?xrdcl.requuid=cb45ecd2-2a69-4b86-81c6-fdcf0b6a91a1] Close returned from st-096-gg5612cq.cern.ch:1095 with: [SUCCESS]
[2026-01-23 11:45:04.024414 +0000][Debug ][ExDbgMsg ][ 1143] [st-096-gg5612cq.cern.ch:1095] Destroying MsgHandler: 0x17a831b0.
===================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
===================================================================================================================================
Full event 128.606 158.05 237.502 135.416 37.796 23
-----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.0846e-05 8.20861e-05 0.000136446 7.8029e-05 1.77114e-05 23
produce:tpcrawdecoder:PDVDTPCReader 18.6636 20.8788 28.0722 19.8209 2.35172 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.828817 0.855916 0.932396 0.847459 0.0244248 23
produce:timingrawdecoder:PDHDTimingRawDecoder 0.0278315 0.0281431 0.0289126 0.0281334 0.000206841 23
produce:ctbrawdecoder:PDHDCTBRawDecoder 0.138426 0.139752 0.141561 0.139675 0.000610888 23
produce:beamevent:BeamEvent 7.5371e-05 0.000115489 0.000600524 9.481e-05 0.00010365 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000351915 0.000394692 0.000615343 0.00038874 4.95941e-05 23
produce:ophit:OpHitFinder 5.1825e-05 7.92092e-05 0.000410022 6.2856e-05 7.10936e-05 23
produce:wclsdatavd:WireCellToolkit 105.372 124.152 189.103 109.898 26.5366 23
produce:gaushit:GausHitFinder 0.00103953 2.25898 10.6489 0.00116452 3.92074 23
produce:nhitsfilter:NumberOfHitsFilter 1.9382e-05 0.000249833 0.000864591 3.2611e-05 0.000322462 23
produce:reco3d:SpacePointSolver 8.1514e-05 2.54817 17.1206 0.000116448 4.97391 23
produce:hitpdune:DisambigFromSpacePoints 0.000174384 0.340067 1.97122 0.000805571 0.609501 23
produce:cluster3d:Cluster3D 0.0003816 2.73159 35.6577 0.0325767 7.28043 23
[art]:TriggerResults:TriggerResultInserter 1.7692e-05 3.28603e-05 6.0332e-05 2.8904e-05 1.05686e-05 23
end_path:out1:RootOutput 4.17e-06 7.91087e-06 1.7358e-05 6.922e-06 2.76407e-06 23
end_path:out1:RootOutput(write) 3.24243 4.08419 6.0635 3.45248 0.95817 23
===================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5379.22 MB
Peak resident set size usage (VmHWM): 3449.6 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
[2026-01-23 11:45:06.139543 +0000][Debug ][JobMgr ][ 1143] Stopping the job manager...
[2026-01-23 11:45:06.139776 +0000][Debug ][JobMgr ][ 1143] Job manager stopped
[2026-01-23 11:45:06.139942 +0000][Debug ][TaskMgr ][ 1143] Stopping the task manager...
[2026-01-23 11:45:06.140124 +0000][Debug ][TaskMgr ][ 1143] Task manager stopped
[2026-01-23 11:45:06.140414 +0000][Debug ][Poller ][ 1143] Stopping the poller...
[2026-01-23 11:45:06.140525 +0000][Debug ][AsyncSock ][ 1143] [st-096-gg5612cq.cern.ch:1095.0] Closing the socket
[2026-01-23 11:45:06.140578 +0000][Debug ][Poller ][ 1143] <[::ffff:192.41.105.37]:57406><--><[::ffff:128.142.170.78]:1095> Removing socket from the poller
[2026-01-23 11:45:06.140647 +0000][Debug ][PostMaster ][ 1143] [st-096-gg5612cq.cern.ch:1095] Destroying stream
[2026-01-23 11:45:06.140697 +0000][Debug ][AsyncSock ][ 1143] [st-096-gg5612cq.cern.ch:1095.0] Closing the socket
Output files:
\tReco: np02vd_raw_run040346_0323_df-s04-d0_dw_0_20251101T162245_reco_stage0_20260123T114503_offline.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_0323_df-s04-d0_dw_0_20251101T162245_reco_stage0_20260123T114503_offline.root
Ran successfully
{
"name": "np02vd_raw_run040346_0323_df-s04-d0_dw_0_20251101T162245_reco_stage0_20260123T114503_offline.root",
"namespace": "usertests",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_17_00d00",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage0_protodunevd_offline_hv220.fcl",
"dune.campaign": "vd-protodune-det-reco-test",
"core.start_time": 1769165010.0,
"core.end_time": 1769168706.0,
"core.events": [
29752,
29756,
29760,
29764,
29768,
29772,
29776,
29780,
29784,
29788,
29792,
29796,
29800,
29804,
29808,
29812,
29816,
29820,
29824,
29828,
29832,
29836,
29840
],
"core.event_count": 23,
"core.first_event_number": 29752,
"core.last_event_number": 29840,
"core.data_stream": "cosmics",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
]
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_0323_df-s04-d0_dw_0_20251101T162245.hdf5"
}
]
} 