Jobsub ID 241403.128@dunegpschedd01.fnal.gov
| Jobsub ID | 241403.128@dunegpschedd01.fnal.gov | 
| Workflow ID | 9495 | 
| Stage ID | 1 | 
| User name | imawby@fnal.gov | 
| HTCondor Group | group_dune | 
| Requested | Processors | 1 | 
| GPU | No | 
| RSS bytes | 1048576000 (1000 MiB) | 
| Wall seconds limit | 7200 (2 hours) | 
| Submitted time | 2025-10-31 13:41:03 | 
| Site | US_PuertoRico | 
| Entry | CMS_T3_US_PuertoRico_UPRM | 
| Last heartbeat | 2025-10-31 14:11:57 | 
| From worker node | Hostname | compute-1-4.local | 
| cpuinfo | Intel(R) Xeon(R) CPU           W3550  @ 3.07GHz | 
| OS release | Scientific Linux release 7.9 (Nitrogen) | 
| Processors | 1 | 
| RSS bytes | 1048576000 (1000 MiB) | 
| Wall seconds limit | 171000 (47 hours) | 
| GPU |  | 
| Inner Apptainer? | True | 
| Job state | finished | 
| Started | 2025-10-31 13:43:23 | 
| Input files | fardet-hd:nu_dune10kt_1x2x6_1113_635_20230827T125342Z_gen_g4_detsim_hitreco__20240223T221959Z_reco2.root
  | 
| Jobscript | Exit code | 0 | 
| Real time | 28m (1692s) | 
| CPU time | 15m (954s = 56%) | 
| Max RSS bytes | 1209053184 (1153 MiB) | 
| Outputting started | 2025-10-31 14:11:37 | 
| Output files | https://fndcadoor.fnal.gov:2880/dune/scratch/users/imawby/kalmanAlg_nu_0/fnal/09495/1/001/ClusterValidation_WithAlg_nu_dune10kt_1x2x6_1113_635_20230827T125342Z_gen_g4_detsim_hitreco__20240223T221959Z_reco2.root
  | 
| Finished | 2025-10-31 14:11:57 | 
| Saved logs | justin-logs:241403.128-dunegpschedd01.fnal.gov.logs.tgz | 
| List job events     Cached HTCondor job logs | 
Jobscript log (last 10,000 characters)
--------------------------------------------------
Input PFN = root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/f4/40/nu_dune10kt_1x2x6_1113_635_20230827T125342Z_gen_g4_detsim_hitreco__20240223T221959Z_reco2.root
lar exit code 0
=== Start last 100 lines of lar log file ===
Failure in algorithm Alg0002, LArDLMaster, STATUS_CODE_NOT_INITIALIZED
Begin processing the 24th record. run: 1113 subRun: 1 event: 63524 at 31-Oct-2025 09:59:50 AST
Begin processing the 25th record. run: 1113 subRun: 1 event: 63525 at 31-Oct-2025 09:59:56 AST
Begin processing the 26th record. run: 1113 subRun: 1 event: 63526 at 31-Oct-2025 10:00:03 AST
Begin processing the 27th record. run: 1113 subRun: 1 event: 63527 at 31-Oct-2025 10:00:14 AST
Begin processing the 28th record. run: 1113 subRun: 1 event: 63528 at 31-Oct-2025 10:00:21 AST
Begin processing the 29th record. run: 1113 subRun: 1 event: 63529 at 31-Oct-2025 10:00:27 AST
Begin processing the 30th record. run: 1113 subRun: 1 event: 63530 at 31-Oct-2025 10:00:35 AST
Begin processing the 31st record. run: 1113 subRun: 1 event: 63531 at 31-Oct-2025 10:00:57 AST
Begin processing the 32nd record. run: 1113 subRun: 1 event: 63532 at 31-Oct-2025 10:01:04 AST
Begin processing the 33rd record. run: 1113 subRun: 1 event: 63533 at 31-Oct-2025 10:01:11 AST
Begin processing the 34th record. run: 1113 subRun: 1 event: 63534 at 31-Oct-2025 10:01:19 AST
Begin processing the 35th record. run: 1113 subRun: 1 event: 63535 at 31-Oct-2025 10:01:26 AST
Begin processing the 36th record. run: 1113 subRun: 1 event: 63536 at 31-Oct-2025 10:01:33 AST
Begin processing the 37th record. run: 1113 subRun: 1 event: 63537 at 31-Oct-2025 10:01:40 AST
Begin processing the 38th record. run: 1113 subRun: 1 event: 63538 at 31-Oct-2025 10:01:46 AST
Begin processing the 39th record. run: 1113 subRun: 1 event: 63539 at 31-Oct-2025 10:01:53 AST
Begin processing the 40th record. run: 1113 subRun: 1 event: 63540 at 31-Oct-2025 10:02:00 AST
Begin processing the 41st record. run: 1113 subRun: 1 event: 63541 at 31-Oct-2025 10:02:07 AST
Begin processing the 42nd record. run: 1113 subRun: 1 event: 63542 at 31-Oct-2025 10:02:14 AST
Begin processing the 43rd record. run: 1113 subRun: 1 event: 63543 at 31-Oct-2025 10:02:27 AST
Begin processing the 44th record. run: 1113 subRun: 1 event: 63544 at 31-Oct-2025 10:02:33 AST
Begin processing the 45th record. run: 1113 subRun: 1 event: 63545 at 31-Oct-2025 10:03:00 AST
Begin processing the 46th record. run: 1113 subRun: 1 event: 63546 at 31-Oct-2025 10:03:10 AST
Begin processing the 47th record. run: 1113 subRun: 1 event: 63547 at 31-Oct-2025 10:03:20 AST
Begin processing the 48th record. run: 1113 subRun: 1 event: 63548 at 31-Oct-2025 10:03:28 AST
Begin processing the 49th record. run: 1113 subRun: 1 event: 63549 at 31-Oct-2025 10:03:43 AST
Begin processing the 50th record. run: 1113 subRun: 1 event: 63550 at 31-Oct-2025 10:03:51 AST
Begin processing the 51st record. run: 1113 subRun: 1 event: 63551 at 31-Oct-2025 10:03:58 AST
Begin processing the 52nd record. run: 1113 subRun: 1 event: 63552 at 31-Oct-2025 10:04:04 AST
Begin processing the 53rd record. run: 1113 subRun: 1 event: 63553 at 31-Oct-2025 10:04:11 AST
Begin processing the 54th record. run: 1113 subRun: 1 event: 63554 at 31-Oct-2025 10:04:21 AST
Begin processing the 55th record. run: 1113 subRun: 1 event: 63555 at 31-Oct-2025 10:04:29 AST
Begin processing the 56th record. run: 1113 subRun: 1 event: 63556 at 31-Oct-2025 10:04:38 AST
Begin processing the 57th record. run: 1113 subRun: 1 event: 63557 at 31-Oct-2025 10:04:45 AST
Begin processing the 58th record. run: 1113 subRun: 1 event: 63558 at 31-Oct-2025 10:04:51 AST
Begin processing the 59th record. run: 1113 subRun: 1 event: 63559 at 31-Oct-2025 10:05:01 AST
Begin processing the 60th record. run: 1113 subRun: 1 event: 63560 at 31-Oct-2025 10:05:08 AST
Begin processing the 61st record. run: 1113 subRun: 1 event: 63561 at 31-Oct-2025 10:05:15 AST
Begin processing the 62nd record. run: 1113 subRun: 1 event: 63562 at 31-Oct-2025 10:05:23 AST
Begin processing the 63rd record. run: 1113 subRun: 1 event: 63563 at 31-Oct-2025 10:05:30 AST
Begin processing the 64th record. run: 1113 subRun: 1 event: 63564 at 31-Oct-2025 10:05:40 AST
Begin processing the 65th record. run: 1113 subRun: 1 event: 63565 at 31-Oct-2025 10:05:46 AST
Begin processing the 66th record. run: 1113 subRun: 1 event: 63566 at 31-Oct-2025 10:05:53 AST
Begin processing the 67th record. run: 1113 subRun: 1 event: 63567 at 31-Oct-2025 10:06:01 AST
Begin processing the 68th record. run: 1113 subRun: 1 event: 63568 at 31-Oct-2025 10:06:08 AST
Begin processing the 69th record. run: 1113 subRun: 1 event: 63569 at 31-Oct-2025 10:06:25 AST
Begin processing the 70th record. run: 1113 subRun: 1 event: 63570 at 31-Oct-2025 10:06:31 AST
Begin processing the 71st record. run: 1113 subRun: 1 event: 63571 at 31-Oct-2025 10:06:39 AST
Begin processing the 72nd record. run: 1113 subRun: 1 event: 63572 at 31-Oct-2025 10:06:46 AST
Begin processing the 73rd record. run: 1113 subRun: 1 event: 63573 at 31-Oct-2025 10:06:54 AST
Begin processing the 74th record. run: 1113 subRun: 1 event: 63574 at 31-Oct-2025 10:07:02 AST
Begin processing the 75th record. run: 1113 subRun: 1 event: 63575 at 31-Oct-2025 10:07:11 AST
Begin processing the 76th record. run: 1113 subRun: 1 event: 63576 at 31-Oct-2025 10:07:17 AST
Begin processing the 77th record. run: 1113 subRun: 1 event: 63577 at 31-Oct-2025 10:07:23 AST
Begin processing the 78th record. run: 1113 subRun: 1 event: 63578 at 31-Oct-2025 10:07:32 AST
Begin processing the 79th record. run: 1113 subRun: 1 event: 63579 at 31-Oct-2025 10:07:39 AST
Begin processing the 80th record. run: 1113 subRun: 1 event: 63580 at 31-Oct-2025 10:07:47 AST
Begin processing the 81st record. run: 1113 subRun: 1 event: 63581 at 31-Oct-2025 10:07:54 AST
Begin processing the 82nd record. run: 1113 subRun: 1 event: 63582 at 31-Oct-2025 10:08:01 AST
Begin processing the 83rd record. run: 1113 subRun: 1 event: 63583 at 31-Oct-2025 10:08:08 AST
Begin processing the 84th record. run: 1113 subRun: 1 event: 63584 at 31-Oct-2025 10:08:16 AST
Begin processing the 85th record. run: 1113 subRun: 1 event: 63585 at 31-Oct-2025 10:08:25 AST
Begin processing the 86th record. run: 1113 subRun: 1 event: 63586 at 31-Oct-2025 10:08:37 AST
Begin processing the 87th record. run: 1113 subRun: 1 event: 63587 at 31-Oct-2025 10:08:45 AST
Begin processing the 88th record. run: 1113 subRun: 1 event: 63588 at 31-Oct-2025 10:08:58 AST
Begin processing the 89th record. run: 1113 subRun: 1 event: 63589 at 31-Oct-2025 10:09:07 AST
Begin processing the 90th record. run: 1113 subRun: 1 event: 63590 at 31-Oct-2025 10:09:20 AST
Begin processing the 91st record. run: 1113 subRun: 1 event: 63591 at 31-Oct-2025 10:09:27 AST
Begin processing the 92nd record. run: 1113 subRun: 1 event: 63592 at 31-Oct-2025 10:09:34 AST
Begin processing the 93rd record. run: 1113 subRun: 1 event: 63593 at 31-Oct-2025 10:09:43 AST
Begin processing the 94th record. run: 1113 subRun: 1 event: 63594 at 31-Oct-2025 10:10:32 AST
Begin processing the 95th record. run: 1113 subRun: 1 event: 63595 at 31-Oct-2025 10:10:40 AST
Begin processing the 96th record. run: 1113 subRun: 1 event: 63596 at 31-Oct-2025 10:10:47 AST
Begin processing the 97th record. run: 1113 subRun: 1 event: 63597 at 31-Oct-2025 10:10:56 AST
Begin processing the 98th record. run: 1113 subRun: 1 event: 63598 at 31-Oct-2025 10:11:06 AST
Begin processing the 99th record. run: 1113 subRun: 1 event: 63599 at 31-Oct-2025 10:11:13 AST
Begin processing the 100th record. run: 1113 subRun: 1 event: 63600 at 31-Oct-2025 10:11:24 AST
31-Oct-2025 10:11:33 AST  Closed output file "nu_dune10kt_1x2x6_1113_635_20230827T125342Z_gen_g4_detsim_hitreco__20240223T221959Z_reco2_reco2.root"
31-Oct-2025 10:11:34 AST  Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/fardet-hd/f4/40/nu_dune10kt_1x2x6_1113_635_20230827T125342Z_gen_g4_detsim_hitreco__20240223T221959Z_reco2.root"
================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                     0.667231       9.27305       162.387       6.0084        16.2793        100    
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                         0.122524      0.177508       0.22041      0.175354      0.0140129       100    
reco:pandora:StandardPandora                   0.485507       9.07727       161.974       5.79804       16.256         100    
[art]:TriggerResults:TriggerResultInserter    1.6922e-05    4.05903e-05   0.000213386   2.88995e-05   2.88757e-05      100    
end_path:out1:RootOutput                       3.026e-06    5.38854e-06   0.000135538   3.5185e-06    1.34593e-05      100    
end_path:out1:RootOutput(write)               0.00182038     0.0180248     0.242888      0.0138093     0.0264328       100    
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
  Peak virtual memory usage (VmPeak)  : 2448.66 MB
  Peak resident set size usage (VmHWM): 1209.05 MB
====================================================================================================
Art has completed and will exit with status 0.
=== End last 100 lines of lar log file ===
mv: cannot stat 'ClusterValidation_WithoutAlg.root': No such file or directory