justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 270289.0@dunegpschedd01.fnal.gov

Jobsub ID270289.0@dunegpschedd01.fnal.gov
Workflow ID11345
Stage ID1
User namepmelas@fnal.gov
RequestedProcessors1
GPUNo
RSS bytes8388608000 (8000 MiB)
Wall seconds limit86400 (24 hours)
Submitted time2025-12-26 18:47:46
SiteUK_QMUL
EntryDUNE_UK_London_QMUL_arcce03
Last heartbeat2025-12-26 23:32:55
From worker nodeHostnamecn008.htc.esc.qmul
cpuinfoIntel(R) Xeon(R) Gold 6248R CPU @ 3.00GHz
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes10485760000 (10000 MiB)
Wall seconds limit171000 (47 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-12-26 18:48:22
Input filesmonte-carlo-011345-000001
JobscriptExit code0
Real time4h (16864s)
CPU time4h (16606s = 98%)
Max RSS bytes4236333056 (4040 MiB)
Outputting started2025-12-26 23:29:27
Output fileshttps://fndcadoor.fnal.gov:2880/dune/scratch/users/pmelas/fnal/11345/1/001/reco_stage1.root
Finished2025-12-26 23:32:55
Saved logsjustin-logs:270289.0-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

l pdsp_RemoveBadChannels
ToolBasedRawDigitPrepService:endEvent: End processing run 1 event 10 with 7 tools.
CookedFrameSource: got 15360 recob::Wire objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 134237 traces, 0 summary
Retagger: tagging trace set: gauss with 107366 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 107366 traces tagged "gauss"
FrameSaver: q=3.85863e+06 n=1980937 tag=gauss
wclsFrameSaver: saving 134237 traces tagged "wiener"
FrameSaver: q=4.04458e+06 n=1780265 tag=wiener
72 X, 28 U, 34 V bad channels
Finding XUV coincidences...
C:0 T:0 24202 XUs and 23309 XVs -> 129 XUVs
C:0 T:1 36977 XUs and 34105 XVs -> 1968 XUVs
C:0 T:2 187988 XUs and 187011 XVs -> 9828 XUVs
C:0 T:3 68856 XUs and 72379 XVs -> 545 XUVs
C:0 T:4 12164 XUs and 15978 XVs -> 89 XUVs
C:0 T:5 44806 XUs and 40452 XVs -> 3624 XUVs
C:0 T:6 94561 XUs and 139315 XVs -> 4405 XUVs
C:0 T:7 35268 XUs and 54546 XVs -> 383 XUVs
C:0 T:8 8076 XUs and 6378 XVs -> 144 XUVs
C:0 T:9 45273 XUs and 44731 XVs -> 2875 XUVs
C:0 T:10 54945 XUs and 32955 XVs -> 4872 XUVs
C:0 T:11 102 XUs and 47 XVs -> 6 XUVs
28868 XUVs total
11968 collection wire objects
26124 potential space points
Neighbour search...
1526346 tests to find 737372 neighbours
Iterating with no regularization...
Begin: 1.03209e+08
0 8.48172e+07
1 8.38861e+07
2 8.37935e+07
3 8.3774e+07
Now with regularization...
Begin: 7.29842e+07
0 7.29724e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
run: 1 ; subrun: 0 ; event: 10
evttime: 411359273
fLifetime: 30000 [us]
run: 1 ; subrun: 0 ; event: 10
evttime: 411359273
fLifetime: 30000 [us]
26-Dec-2025 23:29:24 GMT  Closed output file "reco_stage1.root"
26-Dec-2025 23:29:24 GMT  Closed input file "detsim_stage2.root"
DataPrepModule::endJob: # events processed: 10
DataPrepModule::endJob:   # events skipped: 0

====================================================================================================================================
TimeTracker printout (sec)                            Min           Avg           Max         Median          RMS         nEvts   
====================================================================================================================================
Full event                                          520.959       736.282       938.118       777.954       120.351        10     
------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                            0.00109024    0.00556246     0.0133796    0.00553006    0.00319692       10     
reco:ophit:OpHitFinder                             0.373731      0.440367      0.527755      0.432964      0.0389758       10     
reco:opflash:OpFlashFinder                         0.210053      0.293358      0.386837      0.291305      0.0554955       10     
reco:opslicer:OpSlicer                             0.759844       1.04986       1.45284       1.04819      0.179955        10     
reco:caldata:DataPrepModule                         7.91875       8.80088       9.34736       8.88713      0.343596        10     
reco:wclsdatasp:WireCellToolkit                     66.0919       67.272        69.8618       67.1383       1.06552        10     
reco:gaushit:GausHitFinder                          1.66311       2.27044       2.88621       2.25095      0.387725        10     
reco:nhitsfilter:NumberOfHitsFilter               0.000810127   0.00110397    0.00165024    0.00108653    0.000217793      10     
reco:reco3d:SpacePointSolver                        12.6176       26.3935       44.3625       27.5008       9.67414        10     
reco:hitpdune:DisambigFromSpacePoints               2.02876       3.85265       5.81633       4.07832       1.10222        10     
reco:pandora:StandardPandora                        34.0571       63.9741       91.3967       60.1592       19.703         10     
reco:pandoraWriter:StandardPandora                 0.545279      0.770053      0.898899      0.779497      0.108198        10     
reco:pandoraTrack:LArPandoraTrackCreation           10.7549       16.164        21.2889       16.4902       3.00066        10     
reco:pandoraShower:LArPandoraShowerCreation         10.0002       17.3062       24.3596       17.4589       5.06937        10     
reco:pandoracalo:Calorimetry                        5.24743       7.59994       9.81431       7.8352        1.3666         10     
reco:pandoracalonosce:Calorimetry                   4.97791       7.1618        9.22023       7.4038        1.39221        10     
reco:pandorapid:Chi2ParticleID                    0.00875663     0.0108644     0.0146038     0.0105081    0.00157267       10     
reco:pandoracalinoxyzt:CalibrationdEdXPDSP         0.0697475      0.10685      0.146274      0.106054      0.0221093       10     
reco:pandoracalipidnoxyzt:Chi2ParticleID          0.00858436     0.0105192     0.0134556     0.0102497    0.00137583       10     
reco:pandoraShowercalo:ShowerCalorimetry            8.58243       16.8291       25.6038       15.9214       5.68362        10     
reco:pandoraShowercalonosce:ShowerCalorimetry       7.65601       16.4167       24.3096       16.7086       5.60419        10     
reco:emtrkmichelid:EmTrackMichelId                  246.073       322.527       390.483       315.564        43.85         10     
reco:crttag:SingleCRTMatchingProducer              0.0322876     0.0417379     0.0538003     0.040352     0.00686584       10     
reco:crtreco:TwoCRTMatchingProducer                0.0250406     0.0339225     0.0428405     0.0340922    0.00594066       10     
reco:anodepiercerst0:T0RecoAnodePiercers            1.14077       1.57124       2.07961       1.49891      0.311565        10     
reco:pandora2Track:LArPandoraTrackCreation          32.258        50.1889       75.6385       51.6279       12.4994        10     
reco:pandora2calo:Calorimetry                       12.3754       23.2411       34.7159       23.7389       6.47022        10     
reco:pandora2calonosce:Calorimetry                  12.2582       22.8805       32.5578       22.9671       6.09029        10     
reco:pandora2pid:Chi2ParticleID                    0.0199252     0.0252616     0.0325001     0.0237219    0.00435808       10     
reco:pandora2calinoxyzt:CalibrationdEdXPDSP        0.0948358     0.128781       0.15119       0.13456      0.0182026       10     
reco:pandora2calipidnoxyzt:Chi2ParticleID          0.0190725     0.0241532     0.0311659     0.0240732    0.00374968       10     
reco:pandora2Shower:LArPandoraShowerCreation        10.4121       17.0999       22.5624       17.1782       4.07365        10     
reco:pandora2Showercalo:ShowerCalorimetry           10.5866       20.2867       26.9433       21.2362       4.91227        10     
reco:pandora2Showercalonosce:ShowerCalorimetry      8.95659       17.1154       24.7886       16.1178       4.32423        10     
[art]:TriggerResults:TriggerResultInserter        5.4355e-05    0.000116928   0.000279944   9.84875e-05   5.98072e-05      10     
end_path:out1:RootOutput                           7.011e-06    1.28776e-05   2.1108e-05    1.1177e-05    4.37875e-06      10     
end_path:out1:RootOutput(write)                     2.82964       4.18217       5.28253       4.24999      0.802695        10     
====================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5044.72 MB
  Peak resident set size usage (VmHWM): 4236.33 MB
  Details saved in: 'mem.db'
====================================================================================================
ToolBasedRawDigitPrepService:dtor: Event count: 10
ToolBasedRawDigitPrepService:dtor:  Call count: 60
ToolBasedRawDigitPrepService:dtor: Time report for 7 tools.
ToolBasedRawDigitPrepService:dtor:                    digitReader:   0.11 sec/event
ToolBasedRawDigitPrepService:dtor:                adcSampleFiller:   0.34 sec/event
ToolBasedRawDigitPrepService:dtor:                adcScaleAdcToKe:   0.06 sec/event
ToolBasedRawDigitPrepService:dtor:          pdspTailPedRemovalZKe:   5.49 sec/event
ToolBasedRawDigitPrepService:dtor:         adcKeepAllSignalFinder:   0.31 sec/event
ToolBasedRawDigitPrepService:dtor:                adcScaleKeToAdc:   0.07 sec/event
ToolBasedRawDigitPrepService:dtor:         pdsp_RemoveBadChannels:   0.01 sec/event
Art has completed and will exit with status 0.
lar exit code 0
.:
total 10602208
-rw-r--r-- 1 pildune32 pildune 2474562288 Dec 26 21:23 detsim_stage2.root
-rw-r--r-- 1 pildune32 pildune 2348356597 Dec 26 21:20 detsim_stage1.root
-rw-r--r-- 1 pildune32 pildune 2168170605 Dec 26 23:29 reco_stage1.root
-rw-r--r-- 1 pildune32 pildune 1929805362 Dec 26 19:18 g4_stage2.root
-rw-r--r-- 1 pildune32 pildune 1858715920 Dec 26 18:57 g4_stage1.root
-rw-r--r-- 1 pildune32 pildune   76432090 Dec 26 23:19 Pandora_Events.pndr
-rw-r--r-- 1 pildune32 pildune     242991 Dec 26 23:29 jobscript.log
-rw-r--r-- 1 pildune32 pildune     217088 Dec 26 23:29 mem.db
-rw-r--r-- 1 pildune32 pildune      62376 Dec 26 23:29 Pandora_Geometry.xml
-rw-r--r-- 1 pildune32 pildune      36864 Dec 26 23:29 time.db
-rw-r--r-- 1 pildune32 pildune        519 Dec 26 21:23 detsim_single_protoDUNE_hist.root
-rw-r--r-- 1 pildune32 pildune        519 Dec 26 19:18 g4_protoDUNE_hist.root
-rw-r--r-- 1 pildune32 pildune        519 Dec 26 23:29 protoDUNE_reco1_hist.root
-rw-r--r-- 1 pildune32 pildune         52 Dec 26 18:48 all-input-dids.txt
-rw-r--r-- 1 pildune32 pildune          7 Dec 26 23:29 justin-processed-pfns.txt
-rw-r--r-- 1 pildune32 pildune          0 Dec 26 21:33 ProtoDUNE.log
-rw-r--r-- 1 pildune32 pildune          0 Dec 26 21:23 debugprod.log
justIN time: 2026-02-07 03:36:50 UTC       justIN version: 01.06.00