Build: #279 failed

Job: Test MPI Task Tclean Many Linux 2.28 Rocky 8.10 Docker was successful

Build log

The build generated 105,796 lines of output.   The output is too long and has been truncated to the last 1,000 lines. Download or view full build log. You can find logs from previous runs here.

16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-1        Time degrid 0.05
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-1        Time degrid 0.02
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.03
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.02
16-Jul-2026 02:16:12 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:12 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8830.19]
16-Jul-2026 02:16:12 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (3.4511,0.0279435) (0,0)
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-3        Time degrid 0.01
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-3        Time degrid 0.02
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.05
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.05
16-Jul-2026 02:16:12 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:12 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8583.62]
16-Jul-2026 02:16:12 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (3.59508,0.106853) (0,0)
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-2        Time degrid 0.01
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-2        Time degrid 0.01
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.07
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.02
16-Jul-2026 02:16:12 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:12 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8838.74]
16-Jul-2026 02:16:12 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (3.56724,0.306496) (0,0)
16-Jul-2026 02:16:12 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.09
16-Jul-2026 02:16:14 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.02
16-Jul-2026 02:16:14 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:14 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [-8.47029]
16-Jul-2026 02:16:14 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (0.293379,-0.0470373) (0,0)
16-Jul-2026 02:16:14 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.06
16-Jul-2026 02:16:14 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0
16-Jul-2026 02:16:14 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.06
16-Jul-2026 02:16:14 2026-07-16 02:16:12        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.02
16-Jul-2026 02:16:14 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:14 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [-7.52245]
16-Jul-2026 02:16:14 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-0.292433,-0.00438546) (0,0)
16-Jul-2026 02:16:14 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:14 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [-9.40991]
16-Jul-2026 02:16:14 2026-07-16 02:16:12        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (0.304552,0.0255159) (0,0)
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        tclean::::casa        ...done
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.136884, over full image : 0.136884
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 9, 'cyclethreshold': 0.01715674065053463, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        SDAlgorithmBase::deconvolve         [tst] Run mtmfs minor-cycle | CycleThreshold=0.0171567, CycleNiter=9, Gain=0.1
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.94998GHz.Fractional Bandwidth : 60.0001 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 0.136884
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0.910652 (in this run)
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 0.910652  [1]: 0.0487354
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->9 [9], model=0.8268->0.910652, peakres=0.136884->0.0530315, Reached cycleniter.
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 30 iterations.
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3]
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
16-Jul-2026 02:16:14 2026-07-16 02:16:13        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 3 -------------------------------------
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 3 -------------------------------------
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 3 -------------------------------------
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :0
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :0
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :0
16-Jul-2026 02:16:14 2026-07-16 02:16:14        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :1
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :1
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :1
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :1
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :1
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :1
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :2
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :2
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :2
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :2
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :2
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :2
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-1        Time degrid 0.05
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-1        Time degrid 0.01
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.04
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.03
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8830.19]
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (1.40218,-0.00878301) (0,0)
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-3        Time degrid 0.03
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-3        Time degrid 0.01
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.02
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.06
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8583.62]
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (1.4466,0.0436851) (0,0)
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-2        Time degrid 0.06
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToVis::MPIServer-2        Time degrid 0.03
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.01
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.06
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8838.74]
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (1.39209,0.1612) (0,0)
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.03
16-Jul-2026 02:16:14 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.02
16-Jul-2026 02:16:14 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:40 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [-8.47029]
16-Jul-2026 02:16:40 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (0.11933,-0.0197206) (0,0)
16-Jul-2026 02:16:40 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.05
16-Jul-2026 02:16:40 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.03
16-Jul-2026 02:16:40 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:40 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [-7.52245]
16-Jul-2026 02:16:40 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-0.121316,-0.00236836) (0,0)
16-Jul-2026 02:16:40 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.06
16-Jul-2026 02:16:40 2026-07-16 02:16:15        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.03
16-Jul-2026 02:16:40 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:16:40 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [-9.40991]
16-Jul-2026 02:16:40 2026-07-16 02:16:15        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (0.114296,-0.0141075) (0,0)
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        tclean::::casa        ...done
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0555074, over full image : 0.0555074
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        tclean::::casa        Reached global stopping criterion : iteration limit
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        tclean::::casa        getSummary call: fullsummary=False
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SIImageStore::restore         Beam for chan : 0 : 139.82 arcsec, 129.968 arcsec, -86.7085 deg
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SIImageStore::restore         Beam for chan : 0 : 139.82 arcsec, 129.968 arcsec, -86.7085 deg
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.00555074 Jy/beam
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /home/casatest/work/test_task_tclean/tst.alpha.error
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SDAlgorithmMSMFS::restore         Restored images :
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt0  (model=/home/casatest/work/test_task_tclean/tst.model.tt0)
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt1  (model=/home/casatest/work/test_task_tclean/tst.model.tt1)
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        SDAlgorithmMSMFS::restore         
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-3        Total extra cost of heterogeneous array pointing correction = 0.18sec.  Total VBs processed =  72791. The pointingoffsetsigdev used was = []
16-Jul-2026 02:16:40 2026-07-16 02:16:16        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-2        Total extra cost of heterogeneous array pointing correction = 0.19sec.  Total VBs processed =  72852. The pointingoffsetsigdev used was = []
16-Jul-2026 02:16:40 2026-07-16 02:16:17        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-1        Total extra cost of heterogeneous array pointing correction = 0.2sec.  Total VBs processed =  72865. The pointingoffsetsigdev used was = []
16-Jul-2026 02:16:40 2026-07-16 02:16:35        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:16:40 2026-07-16 02:16:35        INFO        ::casa::MPIServer-1        node 1 has completed its job
16-Jul-2026 02:16:40 2026-07-16 02:16:35        INFO        ::casa::MPIServer-3        node 3 has completed its job
16-Jul-2026 02:16:40 2026-07-16 02:16:35        INFO        ::casa::MPIServer-2        node 2 has completed its job
16-Jul-2026 02:16:40 2026-07-16 02:16:36        INFO        tclean::::casa        ...done
16-Jul-2026 02:16:40 2026-07-16 02:16:36        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
16-Jul-2026 02:16:40 2026-07-16 02:16:36        INFO        tclean::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.residual.tt0', 'tst.psf.tt0', 'tst.sumwt.tt0', 'tst.psf.tt1', 'tst.sumwt.tt1', 'tst.psf.tt2', 'tst.sumwt.tt2', 'tst.weight.tt0', 'tst.weight.tt1', 'tst.weight.tt2', 'tst.pb.tt0', 'tst.model.tt0', 'tst.model.tt1', 'tst.residual.tt1', 'tst.mask', 'tst.image.tt0', 'tst.image.tt1', 'tst.alpha', 'tst.alpha.error']
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-16 02:14:21.998726 End time: 2026-07-16 02:16:36.606293
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa        ##### End Task: tclean               #####
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa        ##########################################
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        name::        No table opened.
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        showcache::        The Table Cache is empty.
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa        [ checkall ]
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.image.tt0', 'tst.psf.tt0', 'tst.weight.tt0'] =  [True, True, True] ( Pass : should all be True )
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 0.9574441313743591 at [256, 256, 0, 0] ( Pass : should be 0.96 , Epsilon: 0.05)
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa+        [ check_pix_val ] tst.weight.tt0 : Value is 0.48626992106437683 at [256, 256, 0, 0] ( Pass : should be 0.486 , Epsilon: 0.05)
16-Jul-2026 02:16:40 2026-07-16 02:16:37        INFO        tclean::::casa+        [ check_pix_val ] tst.alpha : Value is -0.0044463020749390125 at [256, 256, 0, 0] ( Pass : should be 0.0 , Epsilon: 0.05)
16-Jul-2026 02:16:40 .2026-07-16 02:16:39        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
16-Jul-2026 02:16:40 2026-07-16 02:16:39        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
16-Jul-2026 02:16:40 2026-07-16 02:16:39        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
16-Jul-2026 02:16:40 2026-07-16 02:16:40        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
16-Jul-2026 02:16:40 2026-07-16 02:16:40        INFO        tclean::::casa        ##########################################
16-Jul-2026 02:16:40 2026-07-16 02:16:40        INFO        tclean::::casa        ##### Begin Task: tclean             #####
16-Jul-2026 02:16:46 2026-07-16 02:16:40        INFO        tclean::::casa        tclean( vis='vla_wideband_2ptg_w_squint.ms', selectdata=True, field='1', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst.awp2', imsize=1200, cell='1.6arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='awp2', facets=1, psfphasecenter='', wprojplanes=6, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=-0.1, normtype='flatnoise', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=True, outlierfile='', weighting='uniform', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=20, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
16-Jul-2026 02:16:46 2026-07-16 02:16:40        INFO        tclean::::casa        Verifying Input Parameters
16-Jul-2026 02:16:46 2026-07-16 02:16:42        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
16-Jul-2026 02:16:46 2026-07-16 02:16:42        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
16-Jul-2026 02:16:46 2026-07-16 02:16:42        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisUtilMethods::continuumDataPartition         
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 4683 7020 [P][M]: 0:2 ROWNUMBER() >= 4683 && ROWNUMBER() <= 7020
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'vla_wideband_2ptg_w_squint.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 2341', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'vla_wideband_2ptg_w_squint.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 2342 && ROWNUMBER() <= 4682', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'vla_wideband_2ptg_w_squint.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 4683 && ROWNUMBER() <= 7020', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImagerVi2::selectData         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImagerVi2::selectData           NRows selected : 7020
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp2] :
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp2] :
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImagerVi2::defineImage         Impars: start
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImagerVi2::defineImage         Shape: [1200, 1200, 1, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.awp2] with ftmachine : awp2
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.6798 GB. Available mem.: 395.7 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:16:46 2026-07-16 02:16:43        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:16:46 2026-07-16 02:16:44        INFO        tclean::::casa        ...done
16-Jul-2026 02:16:46 2026-07-16 02:16:44        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:16:46 2026-07-16 02:16:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:16:46 2026-07-16 02:16:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 2342 && ROWNUMBER() <= 4682 |   NRows selected : 2341
16-Jul-2026 02:16:46 2026-07-16 02:16:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:16:46 2026-07-16 02:16:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 2341 |   NRows selected : 2341
16-Jul-2026 02:16:46 2026-07-16 02:16:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:16:46 2026-07-16 02:16:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 4683 && ROWNUMBER() <= 7020 |   NRows selected : 2338
16-Jul-2026 02:16:46 2026-07-16 02:16:45        INFO        tclean::::casa        ...done
16-Jul-2026 02:16:46 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1] :
16-Jul-2026 02:16:46 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1] :
16-Jul-2026 02:16:46 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
16-Jul-2026 02:16:46 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [1200, 1200, 1, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:16:46 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1] with ftmachine : awp2
16-Jul-2026 02:16:46 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-1         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:16:46 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 0.6798 GB. Available mem.: 395.7 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:16:46 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2] :
16-Jul-2026 02:18:41 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2] :
16-Jul-2026 02:18:41 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
16-Jul-2026 02:18:41 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [1200, 1200, 1, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:18:41 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2] with ftmachine : awp2
16-Jul-2026 02:18:41 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-2         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:18:41 2026-07-16 02:16:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 0.6798 GB. Available mem.: 395.7 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:18:41 2026-07-16 02:16:46        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:18:41 2026-07-16 02:16:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] :
16-Jul-2026 02:18:41 2026-07-16 02:16:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] :
16-Jul-2026 02:18:41 2026-07-16 02:16:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
16-Jul-2026 02:18:41 2026-07-16 02:16:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [1200, 1200, 1, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:18:41 2026-07-16 02:16:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] with ftmachine : awp2
16-Jul-2026 02:18:41 2026-07-16 02:16:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-3         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:18:41 2026-07-16 02:16:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 0.6798 GB. Available mem.: 395.7 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:18:41 2026-07-16 02:16:47        INFO        tclean::::casa        ...done
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImagerVi2::selectData         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImagerVi2::selectData           NRows selected : 7020
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp2] :
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp2] :
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImagerVi2::defineImage         Impars: start
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImagerVi2::defineImage         Shape: [1200, 1200, 1, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.awp2] with ftmachine : awp2
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.6798 GB. Available mem.: 395.7 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:18:41 2026-07-16 02:16:50        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:18:41 2026-07-16 02:16:51        INFO        tclean::::casa        ...done
16-Jul-2026 02:18:41 2026-07-16 02:16:51        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.awp2] : hogbom
16-Jul-2026 02:18:41 2026-07-16 02:16:51        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:18:41 2026-07-16 02:16:52        INFO        tclean::::casa        ...done
16-Jul-2026 02:18:41 2026-07-16 02:16:52        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:18:41 2026-07-16 02:16:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:18:41 2026-07-16 02:16:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:18:41 2026-07-16 02:16:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:18:41
16-Jul-2026 02:18:41 0%....10....20....30....40....50....60....70....80....90....100%
16-Jul-2026 02:18:41
16-Jul-2026 02:18:41 0%....10....20....30....40....50....60....70....80....90....100%
16-Jul-2026 02:18:41 2026-07-16 02:17:48        INFO        tclean::::casa        ...done
16-Jul-2026 02:18:41 2026-07-16 02:17:48        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] onto :tst.awp2
16-Jul-2026 02:18:41 2026-07-16 02:17:48        INFO        SIImageStore::calcSensitivity         [tst.awp2] Theoretical sensitivity (Jy/bm):0.00282974
16-Jul-2026 02:18:41 2026-07-16 02:17:48        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.013018
16-Jul-2026 02:18:41 2026-07-16 02:17:48        INFO        SIImageStore::printBeamSet         Beam : 5.34877 arcsec, 4.3664 arcsec, -84.4348 deg
16-Jul-2026 02:18:41 2026-07-16 02:17:48        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:18:41 2026-07-16 02:17:49        INFO        tclean::::casa        ...done
16-Jul-2026 02:18:41 2026-07-16 02:17:50        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst.awp2 to all nodes :[/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3]
16-Jul-2026 02:18:41 2026-07-16 02:17:50        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1.model
16-Jul-2026 02:18:41 2026-07-16 02:17:50        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2.model
16-Jul-2026 02:18:41 2026-07-16 02:17:50        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3.model
16-Jul-2026 02:18:41 2026-07-16 02:17:50        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
16-Jul-2026 02:18:41 2026-07-16 02:17:50        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:18:41 2026-07-16 02:17:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:18:41 2026-07-16 02:17:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:18:41 2026-07-16 02:17:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:18:41
16-Jul-2026 02:18:41 0%....10....20....30....40....50....60..
16-Jul-2026 02:18:41 0%....10....20....
16-Jul-2026 02:18:41 0%....10....20....30......70....80....90....100%
16-Jul-2026 02:18:41 30....40..40....50....60......50....70....80..60....70....90....100%
16-Jul-2026 02:18:41 ..80....90....100%
16-Jul-2026 02:18:41 2026-07-16 02:18:41        INFO        tclean::::casa        ...done
16-Jul-2026 02:20:46 2026-07-16 02:18:41        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] onto :tst.awp2
16-Jul-2026 02:20:46 2026-07-16 02:18:41        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 0.477513
16-Jul-2026 02:20:46 2026-07-16 02:18:41        INFO        SynthesisDeconvolver::setupMask         [tst.awp2] Initializing new mask to 1.0 for the full image
16-Jul-2026 02:20:46 2026-07-16 02:18:41        INFO        SynthesisDeconvolver::setupMask         [tst.awp2] Number of pixels in the clean mask : 1.44e+06 out of a total of 1.44e+06 pixels. [ 100 % ]
16-Jul-2026 02:20:46 2026-07-16 02:18:41        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.477513, over full image : 0.477513
16-Jul-2026 02:20:46 2026-07-16 02:18:41        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 20, 'cyclethreshold': 0.02387566864490509, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
16-Jul-2026 02:20:46 2026-07-16 02:18:41        INFO        SDAlgorithmBase::deconvolve         [tst.awp2] Run Hogbom minor-cycle | CycleThreshold=0.0238757, CycleNiter=20, Gain=0.1
16-Jul-2026 02:20:46 2026-07-16 02:18:41        INFO        SDAlgorithmBase::deconvolve         [tst.awp2] iters=0->20 [20], model=0->0.425264, peakres=0.477513->0.052249, Reached cycleniter.
16-Jul-2026 02:20:46 2026-07-16 02:18:41        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 20 iterations.
16-Jul-2026 02:20:46 2026-07-16 02:18:42        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst.awp2 to all nodes :[/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3]
16-Jul-2026 02:20:46 2026-07-16 02:18:42        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1.model
16-Jul-2026 02:20:46 2026-07-16 02:18:42        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2.model
16-Jul-2026 02:20:46 2026-07-16 02:18:42        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3.model
16-Jul-2026 02:20:46 2026-07-16 02:18:42        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
16-Jul-2026 02:20:46 2026-07-16 02:18:42        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:20:46 2026-07-16 02:18:42        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
16-Jul-2026 02:20:46 2026-07-16 02:18:42        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
16-Jul-2026 02:20:46 2026-07-16 02:18:42        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
16-Jul-2026 02:20:46
16-Jul-2026 02:20:46 0%....10....20....30..
16-Jul-2026 02:20:46 0%....10....20....40....50....60....70......30....
16-Jul-2026 02:20:46 0%....10....20....80....90....100%
16-Jul-2026 02:20:46 40....50..30....40....60......50....70....80..60....70....90....100%
16-Jul-2026 02:20:46 ..80....90....100%
16-Jul-2026 02:20:46 2026-07-16 02:20:32        INFO        tclean::::casa        ...done
16-Jul-2026 02:20:46 2026-07-16 02:20:32        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] onto :tst.awp2
16-Jul-2026 02:20:46 2026-07-16 02:20:32        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0560292, over full image : 0.0560292
16-Jul-2026 02:20:46 2026-07-16 02:20:32        INFO        tclean::::casa        Reached global stopping criterion : iteration limit
16-Jul-2026 02:20:46 2026-07-16 02:20:32        INFO        tclean::::casa        getSummary call: fullsummary=False
16-Jul-2026 02:20:46 2026-07-16 02:20:32        INFO        SDAlgorithmBase::restore         [tst.awp2] : Restoring model image.
16-Jul-2026 02:20:46 2026-07-16 02:20:32        INFO        SIImageStore::restore         Beam for chan : 0 : 5.34877 arcsec, 4.3664 arcsec, -84.4348 deg
16-Jul-2026 02:20:46 2026-07-16 02:20:32        INFO        task_tclean::SDAlgorithmBase::pbcor         [tst.awp2] : Applying PB correction
16-Jul-2026 02:20:46 2026-07-16 02:20:41        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:20:46 2026-07-16 02:20:41        INFO        ::casa::MPIServer-3        node 3 has completed its job
16-Jul-2026 02:20:46 2026-07-16 02:20:41        INFO        ::casa::MPIServer-2        node 2 has completed its job
16-Jul-2026 02:20:46 2026-07-16 02:20:41        INFO        ::casa::MPIServer-1        node 1 has completed its job
16-Jul-2026 02:20:46 2026-07-16 02:20:42        INFO        tclean::::casa        ...done
16-Jul-2026 02:20:46 2026-07-16 02:20:42        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
16-Jul-2026 02:20:46 2026-07-16 02:20:42        INFO        tclean::::casa        Searching for images with prefix 'tst.awp2'... Found these, writing history into them: ['tst.awp2.gridwt_moswt', 'tst.awp2.psf', 'tst.awp2.sumwt', 'tst.awp2.weight', 'tst.awp2.pb', 'tst.awp2.model', 'tst.awp2.residual', 'tst.awp2.mask', 'tst.awp2.image', 'tst.awp2.image.pbcor']
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-16 02:16:40.079380 End time: 2026-07-16 02:20:42.578463
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        tclean::::casa        ##### End Task: tclean               #####
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        tclean::::casa        ##########################################
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        name::        No table opened.
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        showcache::        The Table Cache is empty.
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        tclean::::casa        [ checkall ]
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.awp2.image'] =  [True] ( Pass : should all be True )
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        tclean::::casa+        [ check_pix_val ] tst.awp2.pb : Value is 0.686262309551239 at [323, 858, 0, 0] ( Pass : should be 0.66 , Epsilon: 0.1)
16-Jul-2026 02:20:46 2026-07-16 02:20:43        INFO        tclean::::casa+        [ check_pix_val ] tst.awp2.image.pbcor : Value is 0.7013260126113892 at [323, 858, 0, 0] ( Pass : should be 0.75 , Epsilon: 0.1)
16-Jul-2026 02:20:46 .s2026-07-16 02:20:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
16-Jul-2026 02:20:46 2026-07-16 02:20:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
16-Jul-2026 02:20:46 2026-07-16 02:20:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
16-Jul-2026 02:20:46 2026-07-16 02:20:46        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
16-Jul-2026 02:20:46 2026-07-16 02:20:46        INFO        tclean::::casa        ##########################################
16-Jul-2026 02:20:46 2026-07-16 02:20:46        INFO        tclean::::casa        ##### Begin Task: tclean             #####
16-Jul-2026 02:20:51 2026-07-16 02:20:46        INFO        tclean::::casa        tclean( vis='refim_point_wterm_vlad.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst.awp', imsize=2048, cell='10.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='awproject', facets=1, psfphasecenter='', wprojplanes=16, vptable='', mosweight=True, aterm=False, psterm=True, wbawp=False, conjbeams=False, cfcache='tst_use_awp.cf', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=-0.1, normtype='flatnoise', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, outlierfile='', weighting='uniform', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
16-Jul-2026 02:20:51 2026-07-16 02:20:46        INFO        tclean::::casa        Verifying Input Parameters
16-Jul-2026 02:20:51 2026-07-16 02:20:48        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
16-Jul-2026 02:20:51 2026-07-16 02:20:48        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
16-Jul-2026 02:20:51 2026-07-16 02:20:48        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisUtilMethods::continuumDataPartition         
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 10065 15093 [P][M]: 0:2 ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 5032', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::selectData         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::selectData           NRows selected : 15093
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp] :
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp] :
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::defineImage         Impars: start
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::defineImage         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.awp] with ftmachine : awprojectft
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        CFCache2::fillCFSFromDisk        No. of CFS* found in tst_use_awp.cf: 0
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        CFCache2::fillCFSFromDisk        No. of WTCFS* found in tst_use_awp.cf: 0
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        AWProjectFT2::setPAIncrement[R&D]        Setting PA increment to 360 deg
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::createAWPFTMachine         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::createAWPFTMachine +        [0, 1e+09, 1e+09, 1e+06]
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::createAWPFTMachine +         to AWP FTM.
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 1.537 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:20:51 2026-07-16 02:20:49        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:20:51 2026-07-16 02:20:50        INFO        tclean::::casa        ...done
16-Jul-2026 02:20:51 2026-07-16 02:20:50        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:20:51 2026-07-16 02:20:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
16-Jul-2026 02:20:51 2026-07-16 02:20:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093 |   NRows selected : 5029
16-Jul-2026 02:20:51 2026-07-16 02:20:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
16-Jul-2026 02:20:51 2026-07-16 02:20:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064 |   NRows selected : 5032
16-Jul-2026 02:20:51 2026-07-16 02:20:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
16-Jul-2026 02:20:51 2026-07-16 02:20:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 5032 |   NRows selected : 5032
16-Jul-2026 02:20:51 2026-07-16 02:20:51        INFO        tclean::::casa        ...done
16-Jul-2026 02:20:51 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1] :
16-Jul-2026 02:20:51 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1] :
16-Jul-2026 02:20:51 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
16-Jul-2026 02:20:51 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
16-Jul-2026 02:20:51 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1] with ftmachine : awprojectft
16-Jul-2026 02:20:51 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst_use_awp.cf: 0
16-Jul-2026 02:20:51 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst_use_awp.cf: 0
16-Jul-2026 02:20:51 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::setPAIncrement[R&D]::MPIServer-1        Setting PA increment to 360 deg
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-1         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-1 +        [0, 1e+09, 1e+09, 1e+06]
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-1 +         to AWP FTM.
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 1.537 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2] :
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2] :
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2] with ftmachine : awprojectft
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst_use_awp.cf: 0
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst_use_awp.cf: 0
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::setPAIncrement[R&D]::MPIServer-2        Setting PA increment to 360 deg
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-2         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-2 +        [0, 1e+09, 1e+09, 1e+06]
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-2 +         to AWP FTM.
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 1.537 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:20:55 2026-07-16 02:20:52        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] :
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] :
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] with ftmachine : awprojectft
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst_use_awp.cf: 0
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst_use_awp.cf: 0
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::setPAIncrement[R&D]::MPIServer-3        Setting PA increment to 360 deg
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-3         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-3 +        [0, 1e+09, 1e+09, 1e+06]
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-3 +         to AWP FTM.
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 1.537 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        tclean::::casa        ...done
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        SynthesisImagerVi2::dryGridding         ---------------------------------------------------- Dry Gridding ---------------------------------------------
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        AWProjectFT2::init[R&D]        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        AWProjectFT2::initializeToSky[R&D]        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:20:55 2026-07-16 02:20:53        WARN        SynthesisImagerVi2::dryGridding (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 3148)        Making a "blank" CFCache (without the A-Term)
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        VB2CFBMap::makeVBRow2CFBMap[R&D]        CFs not cached for -64.1134 deg, dPA = 360 Field ID = 0 Ant1Type, Ant2Type = 0,0
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        AWConvFunc2::makeConvFunction[R&D]        Making a new convolution function for PA=-64.1134deg for field ID 0
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        AWConvFunc2::makeConvFunction[R&D]        Using 16 planes for W-projection
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        AWConvFunc2::makeConvFunction[R&D]        Estimating maximum possible W = 5156.62 (wavelengths)
16-Jul-2026 02:20:55 2026-07-16 02:20:53        INFO        AWConvFunc2::makeConvFunction[R&D]        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
16-Jul-2026 02:20:55 2026-07-16 02:20:54        INFO        AWConvFunc2::makeConvFunction[R&D]        Making 16 w plane(s).
16-Jul-2026 02:20:55 2026-07-16 02:20:54        INFO        AWConvFunc2::makeConvFunction[R&D]        Making 1 frequency plane(s).
16-Jul-2026 02:20:55 2026-07-16 02:20:54        INFO        AWConvFunc2::makeConvFunction[R&D]        CFB Freq. axis [N, Min, Max, Incr. (GHz)]: 1 0.999981 0.999981 0.1
16-Jul-2026 02:20:55 2026-07-16 02:20:54        INFO        AWConvFunc2::makeConvFunction[R&D]        Making empty CFs for baseline type 0
16-Jul-2026 02:20:55 2026-07-16 02:20:54        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
16-Jul-2026 02:20:55 2026-07-16 02:20:54        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
16-Jul-2026 02:20:55 2026-07-16 02:20:54        INFO        CFStore2::makePersistent(pa)[R&D]        Saving CFStore of shape [1, 16, 2] for PA = -64.1134 BaselineType(antType1, antType2) = (0,0)
16-Jul-2026 02:20:55 2026-07-16 02:20:55        INFO        CFStore2::makePersistent(pa)[R&D]        Saving CFStore of shape [1, 16, 2] for PA = -64.1134 BaselineType(antType1, antType2) = (0,0)
16-Jul-2026 02:20:55 2026-07-16 02:20:56        INFO        AWProjectFT2::findConvFunction[R&D]        Convolution function memory footprint:0 KB out of a maximum of 515213 MB
16-Jul-2026 02:20:55 2026-07-16 02:20:56        INFO        SynthesisImagerVi2::fillCFCache         ---------------------------------------------------- fillCFCache ---------------------------------------------
16-Jul-2026 02:23:38 2026-07-16 02:20:56        WARN        SynthesisImagerVi2::fillCFCache (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 3232)        Re-loading the "blank" CFCache for filling
16-Jul-2026 02:23:38 2026-07-16 02:20:56        INFO        CFCache2::fillCFListFromDisk        Loading misc info from CFs
16-Jul-2026 02:23:38 2026-07-16 02:20:56        INFO        CFCache2::fillCFListFromDisk        Loading misc info from CFs
16-Jul-2026 02:23:38 2026-07-16 02:20:56        WARN        crustaceans::makeConvFunction2[R&D]        Filling CFs for baseline type 0, PA slot 0
16-Jul-2026 02:23:38 2026-07-16 02:20:56        INFO        BeamCalc::setBeamCalcGeometries()        Initialisation of geometries for observatory VLA, antenna type STANDARD
16-Jul-2026 02:23:38 2026-07-16 02:20:56        INFO        BeamCalc::setBeamCalcGeometries()        Will use default geometries for VLA STANDARD.
16-Jul-2026 02:23:38 Processing request for geometries from observatory VLA, antenna type STANDARD
16-Jul-2026 02:23:38 2026-07-16 02:21:01        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:0): CF Support: 4 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:06        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:22.9183): CF Support: 5 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:11        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:91.6732): CF Support: 5 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:16        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:206.265): CF Support: 5 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:20        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:366.693): CF Support: 6 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:25        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:572.958): CF Support: 7 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:30        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:825.059): CF Support: 9 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:35        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:1123): CF Support: 10 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:40        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:1466.77): CF Support: 12 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:45        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:1856.38): CF Support: 14 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:50        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:2291.83): CF Support: 17 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:21:55        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:2773.12): CF Support: 19 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:00        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:3300.24): CF Support: 22 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:05        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:3873.19): CF Support: 26 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:10        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:4491.99): CF Support: 29 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:15        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:5156.62): CF Support: 33 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:20        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:0): CF Support: 4 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:25        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:22.9183): CF Support: 5 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:30        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:91.6732): CF Support: 5 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:35        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:206.265): CF Support: 5 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:40        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:366.693): CF Support: 6 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:45        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:572.958): CF Support: 7 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:50        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:825.059): CF Support: 9 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:22:55        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:1123): CF Support: 10 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:23:00        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:1466.77): CF Support: 12 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:23:05        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:1856.38): CF Support: 14 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:23:10        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:2291.83): CF Support: 17 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:23:15        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:2773.12): CF Support: 19 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:23:20        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:3300.24): CF Support: 22 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:23:25        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:3873.19): CF Support: 26 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:23:30        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:4491.99): CF Support: 29 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:23:35        INFO        Moluscs::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:5156.62): CF Support: 33 (6) pixels
16-Jul-2026 02:23:38 2026-07-16 02:23:35        INFO        CFStore2.2::makePersistent[R&D]        Writing CFStore(0,0) of shape [1, 16, 2]
16-Jul-2026 02:23:38 2026-07-16 02:23:36        INFO        CFStore2.2::makePersistent[R&D]        Writing CFStore(0,0) of shape [1, 16, 2]
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        tclean::::casa        reloadCFCache, CMD = 1 toolsi.reloadcfcache()
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        tclean::::casa        reloadCFCache, CMD = 2 toolsi.reloadcfcache()
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        tclean::::casa        reloadCFCache, CMD = 3 toolsi.reloadcfcache()
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-2         -------------------------------------------- Re-load CFCache ---------------------------------------------
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-2         Lazy fill is On
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-1         -------------------------------------------- Re-load CFCache ---------------------------------------------
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-1         Lazy fill is On
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-3         -------------------------------------------- Re-load CFCache ---------------------------------------------
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-3         Lazy fill is On
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:38 2026-07-16 02:23:37        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
16-Jul-2026 02:23:38 2026-07-16 02:23:38        INFO        tclean::::casa        ...done
16-Jul-2026 02:23:38 2026-07-16 02:23:38        INFO        tclean::::casa        reloadCFCache, CMD = 1 toolsi.reloadcfcache()
16-Jul-2026 02:23:38 2026-07-16 02:23:38        INFO        tclean::::casa        reloadCFCache, CMD = 2 toolsi.reloadcfcache()
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        tclean::::casa        reloadCFCache, CMD = 3 toolsi.reloadcfcache()
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-3         -------------------------------------------- Re-load CFCache ---------------------------------------------
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-3         Lazy fill is On
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-2         -------------------------------------------- Re-load CFCache ---------------------------------------------
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-2         Lazy fill is On
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-1         -------------------------------------------- Re-load CFCache ---------------------------------------------
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-1         Lazy fill is On
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst_use_awp.cf: 32
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
16-Jul-2026 02:23:46 2026-07-16 02:23:38        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
16-Jul-2026 02:23:46 2026-07-16 02:23:39        INFO        tclean::::casa        ...done
16-Jul-2026 02:23:46 2026-07-16 02:23:39        INFO        VB2CFBMap::~VB2CFMap[R&D]        Total extra cost of heterogeneous array pointing correction = 0sec.  Total VBs processed =  0. The pointingoffsetsigdev used was = []
16-Jul-2026 02:23:46 2026-07-16 02:23:41        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:23:46 2026-07-16 02:23:42        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:23:46 2026-07-16 02:23:42        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:23:46 2026-07-16 02:23:42        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:23:46 2026-07-16 02:23:42        INFO        tclean::::casa        ...done
16-Jul-2026 02:23:46 2026-07-16 02:23:42        INFO        tclean::::casa        Gathering/Merging/Scattering Weight Density for PSF generation
16-Jul-2026 02:23:46 2026-07-16 02:23:42        INFO        tclean::::casa        ******************************************************
16-Jul-2026 02:23:46 2026-07-16 02:23:42        INFO        tclean::::casa         gather and scatter now
16-Jul-2026 02:23:46 2026-07-16 02:23:42        INFO        tclean::::casa        ******************************************************
16-Jul-2026 02:23:46 2026-07-16 02:23:42        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:23:46 2026-07-16 02:23:43        INFO        tclean::::casa        ...done
16-Jul-2026 02:23:46 2026-07-16 02:23:44        INFO        SynthesisNormalizer::scatterWeightDensity         Send the gridded weight from : tst.awp to all nodes :[/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1, /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2, /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3]
16-Jul-2026 02:23:46 2026-07-16 02:23:44        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:23:46 2026-07-16 02:23:45        INFO        tclean::::casa        ...done
16-Jul-2026 02:23:46 2026-07-16 02:23:45        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.awp] : hogbom
16-Jul-2026 02:23:46 2026-07-16 02:23:45        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        tclean::::casa        ...done
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
16-Jul-2026 02:23:46 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-2        Setting up for weights accumulation during gridding to compute sensitivity pattern.
16-Jul-2026 02:23:46 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-2+        Consequently, the first gridding cycle will be slower than the subsequent ones.
16-Jul-2026 02:23:46 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
16-Jul-2026 02:23:46 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-3        Setting up for weights accumulation during gridding to compute sensitivity pattern.
16-Jul-2026 02:23:51 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-3+        Consequently, the first gridding cycle will be slower than the subsequent ones.
16-Jul-2026 02:23:51 2026-07-16 02:23:46        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
16-Jul-2026 02:23:51 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-1        Setting up for weights accumulation during gridding to compute sensitivity pattern.
16-Jul-2026 02:23:51 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-1+        Consequently, the first gridding cycle will be slower than the subsequent ones.
16-Jul-2026 02:23:51 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.12
16-Jul-2026 02:23:51 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.09
16-Jul-2026 02:23:51 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.11
16-Jul-2026 02:23:51 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.11
16-Jul-2026 02:23:51 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.11
16-Jul-2026 02:23:51 2026-07-16 02:23:46        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.1
16-Jul-2026 02:23:51 2026-07-16 02:23:47        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:23:51 2026-07-16 02:23:47        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8474.84]
16-Jul-2026 02:23:51 2026-07-16 02:23:47        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (0.992538,2.94906e-08) (0,0)
16-Jul-2026 02:23:51 2026-07-16 02:23:47        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:23:51 2026-07-16 02:23:47        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8234.25]
16-Jul-2026 02:23:51 2026-07-16 02:23:47        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (1.39154,-6.08118e-08) (0,0)
16-Jul-2026 02:23:51 2026-07-16 02:23:47        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:23:51 2026-07-16 02:23:47        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8258.9]
16-Jul-2026 02:23:51 2026-07-16 02:23:47        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (1.39034,-2.25006e-08) (0,0)
16-Jul-2026 02:23:51 2026-07-16 02:23:49        INFO        tclean::::casa        ...done
16-Jul-2026 02:23:51 2026-07-16 02:23:49        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1, /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2, /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] onto :tst.awp
16-Jul-2026 02:23:51 2026-07-16 02:23:49        INFO        SIImageStore::calcSensitivity         [tst.awp] Theoretical sensitivity (Jy/bm):0.00632861
16-Jul-2026 02:23:51 2026-07-16 02:23:49        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.043086
16-Jul-2026 02:23:51 2026-07-16 02:23:49        INFO        SIImageStore::printBeamSet         Beam : 85.3879 arcsec, 71.2449 arcsec, -89.0476 deg
16-Jul-2026 02:23:51 2026-07-16 02:23:49        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:23:51 2026-07-16 02:23:50        INFO        tclean::::casa        ...done
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst.awp to all nodes :[/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1, /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2, /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3]
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp.model to /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1.model
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp.model to /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2.model
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp.model to /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3.model
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:23:51 2026-07-16 02:23:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
16-Jul-2026 02:23:51 2026-07-16 02:23:52        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time to massage data 0.07
16-Jul-2026 02:23:51 2026-07-16 02:23:52        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-2        time gridding 0.04
16-Jul-2026 02:23:51 2026-07-16 02:23:52        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time to massage data 0.07
16-Jul-2026 02:23:51 2026-07-16 02:23:52        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-3        time gridding 0.06
16-Jul-2026 02:23:51 2026-07-16 02:23:52        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time to massage data 0.06
16-Jul-2026 02:23:51 2026-07-16 02:23:52        WARN        MPICommandServer::command_request_handler_service::AWProjectFT::finalizeToSky::MPIServer-1        time gridding 0.06
16-Jul-2026 02:23:51 2026-07-16 02:23:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:23:51 2026-07-16 02:23:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8475.52]
16-Jul-2026 02:23:51 2026-07-16 02:23:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (-0.470794,-1.04899) (0,0)
16-Jul-2026 02:23:51 2026-07-16 02:23:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:24:10 2026-07-16 02:23:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8235.06]
16-Jul-2026 02:24:10 2026-07-16 02:23:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-0.996397,0.392977) (0,0)
16-Jul-2026 02:24:10 2026-07-16 02:23:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1        Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
16-Jul-2026 02:24:10 2026-07-16 02:23:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8259.7]
16-Jul-2026 02:24:10 2026-07-16 02:23:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (-1.08331,0.295813) (0,0)
16-Jul-2026 02:24:10 2026-07-16 02:23:53        INFO        tclean::::casa        ...done
16-Jul-2026 02:24:10 2026-07-16 02:23:53        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1, /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2, /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3] onto :tst.awp
16-Jul-2026 02:24:10 2026-07-16 02:23:54        INFO        task_tclean::SDAlgorithmBase::restore         [tst.awp] : Restoring model image.
16-Jul-2026 02:24:10 2026-07-16 02:23:54        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2353)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
16-Jul-2026 02:24:10 2026-07-16 02:23:54        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 85.3879 arcsec, 71.2449 arcsec, -89.0476 deg
16-Jul-2026 02:24:10 2026-07-16 02:23:54        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-3        Total extra cost of heterogeneous array pointing correction = 0.03sec.  Total VBs processed =  15317. The pointingoffsetsigdev used was = []
16-Jul-2026 02:24:10 2026-07-16 02:23:54        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-2        Total extra cost of heterogeneous array pointing correction = 0.05sec.  Total VBs processed =  15562. The pointingoffsetsigdev used was = []
16-Jul-2026 02:24:10 2026-07-16 02:23:54        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-1        Total extra cost of heterogeneous array pointing correction = 0sec.  Total VBs processed =  15798. The pointingoffsetsigdev used was = []
16-Jul-2026 02:24:10 2026-07-16 02:24:04        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:24:10 2026-07-16 02:24:04        INFO        ::casa::MPIServer-3        node 3 has completed its job
16-Jul-2026 02:24:10 2026-07-16 02:24:04        INFO        ::casa::MPIServer-1        node 1 has completed its job
16-Jul-2026 02:24:10 2026-07-16 02:24:04        INFO        ::casa::MPIServer-2        node 2 has completed its job
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa        ...done
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa        Searching for images with prefix 'tst.awp'... Found these, writing history into them: ['tst.awp.gridwt_temp', 'tst.awp.psf', 'tst.awp.sumwt', 'tst.awp.weight', 'tst.awp.pb', 'tst.awp.model', 'tst.awp.residual', 'tst.awp.image']
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-16 02:20:46.026149 End time: 2026-07-16 02:24:05.356636
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa        ##### End Task: tclean               #####
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa        ##########################################
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        name::        No table opened.
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        showcache::        The Table Cache is empty.
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa        [ checkall ]
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.awp.image'] =  [True] ( Pass : should all be True )
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa+        [ check_pix_val ] tst.awp.psf : Value is 1.0 at [1024, 1024, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
16-Jul-2026 02:24:10 2026-07-16 02:24:05        INFO        tclean::::casa+        [ check_pix_val ] tst.awp.image : Value is 0.9958503246307373 at [1158, 1384, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
16-Jul-2026 02:24:10 .s2026-07-16 02:24:06        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
16-Jul-2026 02:24:10 2026-07-16 02:24:06        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
16-Jul-2026 02:24:10 2026-07-16 02:24:06        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
16-Jul-2026 02:24:10 2026-07-16 02:24:07        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
16-Jul-2026 02:24:10 2026-07-16 02:24:07        INFO        tclean::::casa        ##########################################
16-Jul-2026 02:24:10 2026-07-16 02:24:07        INFO        tclean::::casa        ##### Begin Task: tclean             #####
16-Jul-2026 02:24:10 2026-07-16 02:24:07        INFO        tclean::::casa        tclean( vis='vla_wideband_2ptg_w_squint.ms', selectdata=True, field='1', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst.awp2', imsize=1200, cell='1.6arcsec', phasecenter='', stokes='IV', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='awp2', facets=1, psfphasecenter='', wprojplanes=2, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=15.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=-0.1, normtype='flatnoise', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=True, outlierfile='', weighting='uniform', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=20, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
16-Jul-2026 02:24:10 2026-07-16 02:24:07        INFO        tclean::::casa        Verifying Input Parameters
16-Jul-2026 02:24:10 2026-07-16 02:24:09        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
16-Jul-2026 02:24:10 2026-07-16 02:24:09        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
16-Jul-2026 02:24:10 2026-07-16 02:24:09        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
16-Jul-2026 02:24:10 2026-07-16 02:24:10        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
16-Jul-2026 02:24:10 2026-07-16 02:24:10        INFO        SynthesisUtilMethods::continuumDataPartition         
16-Jul-2026 02:24:10 2026-07-16 02:24:10        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 4683 7020 [P][M]: 0:2 ROWNUMBER() >= 4683 && ROWNUMBER() <= 7020
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'vla_wideband_2ptg_w_squint.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 2341', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'vla_wideband_2ptg_w_squint.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 2342 && ROWNUMBER() <= 4682', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'vla_wideband_2ptg_w_squint.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 4683 && ROWNUMBER() <= 7020', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImagerVi2::selectData         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImagerVi2::selectData           NRows selected : 7020
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp2] :
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp2] :
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImagerVi2::defineImage         Impars: start
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImagerVi2::defineImage         Shape: [1200, 1200, 2, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.awp2] with ftmachine : awp2
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 1.36 GB. Available mem.: 395.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:24:13 2026-07-16 02:24:10        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:24:13 2026-07-16 02:24:11        INFO        tclean::::casa        ...done
16-Jul-2026 02:24:13 2026-07-16 02:24:11        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:24:13 2026-07-16 02:24:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:24:13 2026-07-16 02:24:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 2342 && ROWNUMBER() <= 4682 |   NRows selected : 2341
16-Jul-2026 02:24:13 2026-07-16 02:24:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:24:13 2026-07-16 02:24:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 4683 && ROWNUMBER() <= 7020 |   NRows selected : 2338
16-Jul-2026 02:24:13 2026-07-16 02:24:12        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:24:13 2026-07-16 02:24:12        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 2341 |   NRows selected : 2341
16-Jul-2026 02:24:13 2026-07-16 02:24:12        INFO        tclean::::casa        ...done
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1] :
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1] :
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [1200, 1200, 2, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1] with ftmachine : awp2
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-1         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 1.36 GB. Available mem.: 395.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2] :
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2] :
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [1200, 1200, 2, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2] with ftmachine : awp2
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-2         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:24:13 2026-07-16 02:24:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 1.36 GB. Available mem.: 395.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:24:13 2026-07-16 02:24:14        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:24:13 2026-07-16 02:24:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] :
16-Jul-2026 02:24:13 2026-07-16 02:24:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] :
16-Jul-2026 02:24:13 2026-07-16 02:24:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
16-Jul-2026 02:24:13 2026-07-16 02:24:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [1200, 1200, 2, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:24:13 2026-07-16 02:24:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] with ftmachine : awp2
16-Jul-2026 02:24:13 2026-07-16 02:24:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-3         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:26:30 2026-07-16 02:24:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 1.36 GB. Available mem.: 395.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:26:30 2026-07-16 02:24:15        INFO        tclean::::casa        ...done
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImagerVi2::selectData         MS : vla_wideband_2ptg_w_squint.ms | Selecting on fields : 1 | [Opened in readonly mode]
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImagerVi2::selectData           NRows selected : 7020
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp2] :
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp2] :
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImagerVi2::defineImage         Impars: start
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImagerVi2::defineImage         Shape: [1200, 1200, 2, 1] Spectral: [1.48111e+09] at [0] with increment [5.99947e+08]
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.awp2] with ftmachine : awp2
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 1.36 GB. Available mem.: 395.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:26:30 2026-07-16 02:24:17        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:26:30 2026-07-16 02:24:18        INFO        tclean::::casa        ...done
16-Jul-2026 02:26:30 2026-07-16 02:24:18        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.awp2] : hogbom
16-Jul-2026 02:26:30 2026-07-16 02:24:18        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:26:30 2026-07-16 02:24:19        INFO        tclean::::casa        ...done
16-Jul-2026 02:26:30 2026-07-16 02:24:19        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:26:30 2026-07-16 02:24:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:26:30 2026-07-16 02:24:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:26:30 2026-07-16 02:24:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:26:30 paMax 1.89109 painc 0.261799
16-Jul-2026 02:26:30 paMax 3.13298 painc 0.261799
16-Jul-2026 02:26:30 paMax 1.69476 painc 0.261799
16-Jul-2026 02:26:30
16-Jul-2026 02:26:30 0%....10....20....30....40....50....60....70....80....90....100%
16-Jul-2026 02:26:30
16-Jul-2026 02:26:30 0%....10....20....30....40....50....60....70....80....90....100%
16-Jul-2026 02:26:30
16-Jul-2026 02:26:30 0%....10....20....30....40....50....60....70....80....90....100%
16-Jul-2026 02:26:30 2026-07-16 02:26:13        INFO        tclean::::casa        ...done
16-Jul-2026 02:26:30 2026-07-16 02:26:13        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] onto :tst.awp2
16-Jul-2026 02:26:30 2026-07-16 02:26:14        INFO        SIImageStore::calcSensitivity         [tst.awp2] Theoretical sensitivity (Jy/bm):p0:0.00400186 p1:0.00400186
16-Jul-2026 02:26:30 2026-07-16 02:26:14        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.02544
16-Jul-2026 02:26:30 2026-07-16 02:26:14        INFO        SIImageStore::printBeamSet         Restoring Beams
16-Jul-2026 02:26:30 2026-07-16 02:26:14        INFO        SIImageStore::printBeamSet +        Chan    Freq   Vel Pol
16-Jul-2026 02:26:30 2026-07-16 02:26:14        INFO        SIImageStore::printBeamSet +           0 1.5e+09 -70230   I    5.3459 arcsec x    4.3663 arcsec pa=-84.5374 deg
16-Jul-2026 02:26:30 2026-07-16 02:26:14        INFO        SIImageStore::printBeamSet +           0 1.5e+09 -70230   V    5.3459 arcsec x    4.3663 arcsec pa=-84.5374 deg
16-Jul-2026 02:26:30 2026-07-16 02:26:14        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        tclean::::casa        ...done
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst.awp2 to all nodes :[/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3]
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1.model
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2.model
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3.model
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:26:30 2026-07-16 02:26:15        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:26:30
16-Jul-2026 02:26:30 0%....10....20....30....40....50....60....70....80....90....100%
16-Jul-2026 02:26:30
16-Jul-2026 02:26:30 0%....10....20....30....40....50....60....70....80....
16-Jul-2026 02:26:30 0%....10....20....30....40....50....60....70....80....90....100%
16-Jul-2026 02:26:30 90....100%
16-Jul-2026 02:26:30 2026-07-16 02:26:29        INFO        tclean::::casa        ...done
16-Jul-2026 02:26:30 2026-07-16 02:26:29        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] onto :tst.awp2
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 0.458244
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        SynthesisDeconvolver::setupMask         [tst.awp2] Initializing new mask to 1.0 for the full image
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        SynthesisDeconvolver::setupMask         [tst.awp2] Number of pixels in the clean mask : 2.88e+06 out of a total of 2.88e+06 pixels. [ 100 % ]
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.458244, over full image : 0.458244
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 20, 'cyclethreshold': 0.022912221029400826, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        SDAlgorithmBase::deconvolve         [tst.awp2] Run Hogbom minor-cycle on 2 pols | CycleThreshold=0.0229122, CycleNiter=20, Gain=0.1
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        SDAlgorithmBase::deconvolve         [tst.awp2:P0] iters=0->20 [20], model=0->0.408104, peakres=0.458244->0.0501406, Reached cycleniter.
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        SDAlgorithmBase::deconvolve         [tst.awp2:P1] iters=20->20 [0], model=0->0, peakres=0.00489643->0.00489643, Reached cyclethreshold.
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        SDAlgorithmBase::deconvolve         [tst.awp2] Total model flux (over all planes) : 0.408104     Peak Residual (over all planes) : 0.0501406 in C0:P0
16-Jul-2026 02:26:30 2026-07-16 02:26:30        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 20 iterations.
16-Jul-2026 02:27:13 2026-07-16 02:26:30        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst.awp2 to all nodes :[/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3]
16-Jul-2026 02:27:13 2026-07-16 02:26:30        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1.model
16-Jul-2026 02:27:13 2026-07-16 02:26:30        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2.model
16-Jul-2026 02:27:13 2026-07-16 02:26:30        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.awp2.model to /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3.model
16-Jul-2026 02:27:13 2026-07-16 02:26:30        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
16-Jul-2026 02:27:13 2026-07-16 02:26:30        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:13 2026-07-16 02:26:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
16-Jul-2026 02:27:13 2026-07-16 02:26:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
16-Jul-2026 02:27:13 2026-07-16 02:26:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
16-Jul-2026 02:27:13
16-Jul-2026 02:27:13 0%....10....20....30....40....50..
16-Jul-2026 02:27:13 0%....10....20....30....40....50....60....70....80....90....100%
16-Jul-2026 02:27:13 ..60....
16-Jul-2026 02:27:13 0%....10....20....30....40..70....80....50......90....100%
16-Jul-2026 02:27:13 60....70....80....90....100%
16-Jul-2026 02:27:13 2026-07-16 02:27:00        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:13 2026-07-16 02:27:00        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n1, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n2, /home/casatest/work/test_task_tclean/tst.awp2.workdirectory/tst.awp2.n3] onto :tst.awp2
16-Jul-2026 02:27:13 2026-07-16 02:27:00        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0626308, over full image : 0.0626308
16-Jul-2026 02:27:13 2026-07-16 02:27:00        INFO        tclean::::casa        Reached global stopping criterion : iteration limit
16-Jul-2026 02:27:13 2026-07-16 02:27:00        INFO        tclean::::casa        getSummary call: fullsummary=False
16-Jul-2026 02:27:13 2026-07-16 02:27:00        INFO        SDAlgorithmBase::restore         [tst.awp2] : Restoring model image.
16-Jul-2026 02:27:13 2026-07-16 02:27:01        INFO        SIImageStore::restore         Beam for chan : 0 : 5.34588 arcsec, 4.36629 arcsec, -84.5374 deg
16-Jul-2026 02:27:13 2026-07-16 02:27:01        INFO        SIImageStore::restore         Beam for chan : 0 : 5.34588 arcsec, 4.36629 arcsec, -84.5374 deg
16-Jul-2026 02:27:13 2026-07-16 02:27:01        INFO        task_tclean::SDAlgorithmBase::pbcor         [tst.awp2] : Applying PB correction
16-Jul-2026 02:27:13 2026-07-16 02:27:10        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:13 2026-07-16 02:27:10        INFO        ::casa::MPIServer-2        node 2 has completed its job
16-Jul-2026 02:27:13 2026-07-16 02:27:10        INFO        ::casa::MPIServer-3        node 3 has completed its job
16-Jul-2026 02:27:13 2026-07-16 02:27:10        INFO        ::casa::MPIServer-1        node 1 has completed its job
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa        Searching for images with prefix 'tst.awp2'... Found these, writing history into them: ['tst.awp2.gridwt_moswt', 'tst.awp2.psf', 'tst.awp2.sumwt', 'tst.awp2.weight', 'tst.awp2.pb', 'tst.awp2.model', 'tst.awp2.residual', 'tst.awp2.mask', 'tst.awp2.image', 'tst.awp2.image.pbcor']
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-16 02:24:07.034294 End time: 2026-07-16 02:27:11.450068
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa        ##### End Task: tclean               #####
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa        ##########################################
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        name::        No table opened.
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        showcache::        The Table Cache is empty.
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa        [ checkall ]
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.awp2.image'] =  [True] ( Pass : should all be True )
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa+        [ check_pix_val ] tst.awp2.pb : Value is 0.6791477799415588 at [323, 858, 0, 0] ( Pass : should be 0.654 , Epsilon: 0.05)
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa+        [ check_pix_val ] tst.awp2.image.pbcor : Value is 0.6931255459785461 at [323, 858, 0, 0] ( Pass : should be 0.7 , Epsilon: 0.05)
16-Jul-2026 02:27:13 2026-07-16 02:27:11        INFO        tclean::::casa+        [ check_pix_val ] tst.awp2.image.pbcor : Value is -0.02026747539639473 at [323, 858, 1, 0] ( Pass : should be -0.01 , Epsilon: 0.05)
16-Jul-2026 02:27:13 [ checkall ]
16-Jul-2026 02:27:13 [ check_ims ] Image made : ['tst.awp2.image'] =  [True] ( Pass : should all be True )
16-Jul-2026 02:27:13 All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
16-Jul-2026 02:27:13 [check_history] All expected history entries found. (Pass)
16-Jul-2026 02:27:13 [ check_pix_val ] tst.awp2.pb : Value is 0.6791477799415588 at [323, 858, 0, 0] ( Pass : should be 0.654 , Epsilon: 0.05)
16-Jul-2026 02:27:13 [ check_pix_val ] tst.awp2.image.pbcor : Value is 0.6931255459785461 at [323, 858, 0, 0] ( Pass : should be 0.7 , Epsilon: 0.05)
16-Jul-2026 02:27:13 [ check_pix_val ] tst.awp2.image.pbcor : Value is -0.02026747539639473 at [323, 858, 1, 0] ( Pass : should be -0.01 , Epsilon: 0.05)
16-Jul-2026 02:27:13
16-Jul-2026 02:27:13 .2026-07-16 02:27:12        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
16-Jul-2026 02:27:13 2026-07-16 02:27:12        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
16-Jul-2026 02:27:13 2026-07-16 02:27:12        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
16-Jul-2026 02:27:13 2026-07-16 02:27:13        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
16-Jul-2026 02:27:13 2026-07-16 02:27:13        INFO        tclean::::casa        ##########################################
16-Jul-2026 02:27:13 2026-07-16 02:27:13        INFO        tclean::::casa        ##### Begin Task: tclean             #####
16-Jul-2026 02:27:19 2026-07-16 02:27:13        INFO        tclean::::casa        tclean( vis='refim_point_wterm_vlad.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst.wyes', imsize=2048, cell='10.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='wproject', facets=1, psfphasecenter='', wprojplanes=16, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=-0.1, normtype='flatnoise', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, outlierfile='', weighting='uniform', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
16-Jul-2026 02:27:19 2026-07-16 02:27:13        INFO        tclean::::casa        Verifying Input Parameters
16-Jul-2026 02:27:19 2026-07-16 02:27:15        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
16-Jul-2026 02:27:19 2026-07-16 02:27:15        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
16-Jul-2026 02:27:19 2026-07-16 02:27:15        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisUtilMethods::continuumDataPartition         
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 10065 15093 [P][M]: 0:2 ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 5032', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_wterm_vlad.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisImagerVi2::selectData         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisImagerVi2::selectData           NRows selected : 15093
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.wyes] :
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.wyes] :
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisImagerVi2::defineImage         Impars: start
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisImagerVi2::defineImage         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.wyes] with ftmachine : wprojectft
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 1.537 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:27:19 2026-07-16 02:27:16        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:19 2026-07-16 02:27:17        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:19 2026-07-16 02:27:17        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:19 2026-07-16 02:27:17        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
16-Jul-2026 02:27:19 2026-07-16 02:27:17        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
16-Jul-2026 02:27:19 2026-07-16 02:27:17        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064 |   NRows selected : 5032
16-Jul-2026 02:27:19 2026-07-16 02:27:17        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093 |   NRows selected : 5029
16-Jul-2026 02:27:19 2026-07-16 02:27:17        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
16-Jul-2026 02:27:19 2026-07-16 02:27:17        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 5032 |   NRows selected : 5032
16-Jul-2026 02:27:19 2026-07-16 02:27:18        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1] :
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1] :
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1] with ftmachine : wprojectft
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 1.537 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2] :
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2] :
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
16-Jul-2026 02:27:19 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
16-Jul-2026 02:27:33 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2] with ftmachine : wprojectft
16-Jul-2026 02:27:33 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 1.537 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:27:33 2026-07-16 02:27:19        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:33 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] :
16-Jul-2026 02:27:33 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] :
16-Jul-2026 02:27:33 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
16-Jul-2026 02:27:33 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [2048, 2048, 1, 1] Spectral: [9.99981e+08] at [0] with increment [1.00218e+06]
16-Jul-2026 02:27:33 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] with ftmachine : wprojectft
16-Jul-2026 02:27:33 2026-07-16 02:27:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 1.537 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
16-Jul-2026 02:27:33 2026-07-16 02:27:20        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:33 2026-07-16 02:27:23        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:33 2026-07-16 02:27:23        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:27:33 2026-07-16 02:27:23        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:27:33 2026-07-16 02:27:23        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
16-Jul-2026 02:27:33 2026-07-16 02:27:24        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:33 2026-07-16 02:27:24        INFO        tclean::::casa        Gathering/Merging/Scattering Weight Density for PSF generation
16-Jul-2026 02:27:33 2026-07-16 02:27:24        INFO        tclean::::casa        ******************************************************
16-Jul-2026 02:27:33 2026-07-16 02:27:24        INFO        tclean::::casa         gather and scatter now
16-Jul-2026 02:27:33 2026-07-16 02:27:24        INFO        tclean::::casa        ******************************************************
16-Jul-2026 02:27:33 2026-07-16 02:27:24        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:33 2026-07-16 02:27:25        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:33 2026-07-16 02:27:25        INFO        SynthesisNormalizer::scatterWeightDensity         Send the gridded weight from : tst.wyes to all nodes :[/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1, /home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2, /home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3]
16-Jul-2026 02:27:33 2026-07-16 02:27:25        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:33 2026-07-16 02:27:26        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:33 2026-07-16 02:27:26        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.wyes] : hogbom
16-Jul-2026 02:27:33 2026-07-16 02:27:26        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        W projection using 16 planes
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Using maximum possible W = 5156.62 (wavelengths)
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Typical wavelength = 0.299798 (m)
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        W projection using 16 planes
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Using maximum possible W = 5156.62 (wavelengths)
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Typical wavelength = 0.299798 (m)
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        W projection using 16 planes
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Using maximum possible W = 5156.62 (wavelengths)
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Typical wavelength = 0.299798 (m)
16-Jul-2026 02:27:33 2026-07-16 02:27:27        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
16-Jul-2026 02:27:33 2026-07-16 02:27:32        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Convolution support = [16, 16, 20, 20, 24, 32, 36, 44, 52, 64, 72, 84, 96, 112, 124, 140] pixels in Fourier plane
16-Jul-2026 02:27:33 2026-07-16 02:27:32        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Memory used in gridding function = 2 MB from maximum 515213 MB
16-Jul-2026 02:27:33 2026-07-16 02:27:32        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Convolution support = [16, 16, 20, 20, 24, 32, 36, 44, 52, 64, 72, 84, 96, 112, 124, 140] pixels in Fourier plane
16-Jul-2026 02:27:33 2026-07-16 02:27:32        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Memory used in gridding function = 2 MB from maximum 515213 MB
16-Jul-2026 02:27:33 2026-07-16 02:27:32        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Convolution support = [16, 16, 20, 20, 24, 32, 36, 44, 52, 64, 72, 84, 96, 112, 124, 140] pixels in Fourier plane
16-Jul-2026 02:27:33 2026-07-16 02:27:32        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Memory used in gridding function = 2 MB from maximum 515213 MB
16-Jul-2026 02:27:33 2026-07-16 02:27:33        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:59 2026-07-16 02:27:33        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1, /home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2, /home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] onto :tst.wyes
16-Jul-2026 02:27:59 2026-07-16 02:27:33        INFO        SIImageStore::calcSensitivity         [tst.wyes] Theoretical sensitivity (Jy/bm):0.00632763
16-Jul-2026 02:27:59 2026-07-16 02:27:33        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.03914
16-Jul-2026 02:27:59 2026-07-16 02:27:33        INFO        SIImageStore::printBeamSet         Beam : 85.3971 arcsec, 71.2504 arcsec, -89.0441 deg
16-Jul-2026 02:27:59 2026-07-16 02:27:33        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:59 2026-07-16 02:27:34        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::makePrimaryBeam::MPIServer-1         vi2 : Evaluating Primary Beam model onto image grid(s)
16-Jul-2026 02:27:59 2026-07-16 02:27:34        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:59 2026-07-16 02:27:35        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst.wyes to all nodes :[/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1, /home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2, /home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3]
16-Jul-2026 02:27:59 2026-07-16 02:27:35        INFO        SIImageStore::setModelImageOne         Starting model image tst.wyes.model does not exist. No initial prediction will be done
16-Jul-2026 02:27:59 2026-07-16 02:27:35        INFO        SIImageStore::setModelImageOne         Starting model image tst.wyes.model does not exist. No initial prediction will be done
16-Jul-2026 02:27:59 2026-07-16 02:27:35        INFO        SIImageStore::setModelImageOne         Starting model image tst.wyes.model does not exist. No initial prediction will be done
16-Jul-2026 02:27:59 2026-07-16 02:27:35        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
16-Jul-2026 02:27:59 2026-07-16 02:27:35        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:59 2026-07-16 02:27:35        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:27:59 2026-07-16 02:27:35        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:27:59 2026-07-16 02:27:35        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
16-Jul-2026 02:27:59 2026-07-16 02:27:37        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:59 2026-07-16 02:27:37        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n1, /home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n2, /home/casatest/work/test_task_tclean/tst.wyes.workdirectory/tst.wyes.n3] onto :tst.wyes
16-Jul-2026 02:27:59 2026-07-16 02:27:37        INFO        task_tclean::SDAlgorithmBase::restore         [tst.wyes] : Restoring model image.
16-Jul-2026 02:27:59 2026-07-16 02:27:37        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2353)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
16-Jul-2026 02:27:59 2026-07-16 02:27:37        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 85.3971 arcsec, 71.2504 arcsec, -89.0441 deg
16-Jul-2026 02:27:59 2026-07-16 02:27:45        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
16-Jul-2026 02:27:59 2026-07-16 02:27:45        INFO        ::casa::MPIServer-2        node 2 has completed its job
16-Jul-2026 02:27:59 2026-07-16 02:27:46        INFO        ::casa::MPIServer-1        node 1 has completed its job
16-Jul-2026 02:27:59 2026-07-16 02:27:46        INFO        ::casa::MPIServer-3        node 3 has completed its job
16-Jul-2026 02:27:59 2026-07-16 02:27:46        INFO        tclean::::casa        ...done
16-Jul-2026 02:27:59 2026-07-16 02:27:46        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
16-Jul-2026 02:27:59 2026-07-16 02:27:46        INFO        tclean::::casa        Searching for images with prefix 'tst.wyes'... Found these, writing history into them: ['tst.wyes.gridwt_temp', 'tst.wyes.psf', 'tst.wyes.sumwt', 'tst.wyes.pb', 'tst.wyes.residual', 'tst.wyes.image', 'tst.wyes.model']
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-16 02:27:13.101066 End time: 2026-07-16 02:27:46.512563
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa        ##### End Task: tclean               #####
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa        ##########################################
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        name::        No table opened.
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        showcache::        The Table Cache is empty.
16-Jul-2026 02:27:59 [check_tfmask]  T/F mask name for tst.wyes.image is : ['T'] (Pass : should be ['T'])
16-Jul-2026 02:27:59
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa        [ checkall ]
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.wyes.image'] =  [True] ( Pass : should all be True )
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa+        [ check_pix_val ] tst.wyes.psf : Value is 1.0 at [1024, 1024, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa+        [ check_pix_val ] tst.wyes.image : Value is 0.9963292479515076 at [1158, 1384, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
16-Jul-2026 02:27:59 2026-07-16 02:27:47        INFO        tclean::::casa+        [check_tfmask]  T/F mask name for tst.wyes.image is : ['T'] (Pass : should be ['T'])
16-Jul-2026 02:27:59 .s
16-Jul-2026 02:27:59 ----------------------------------------------------------------------
16-Jul-2026 02:27:59 Ran 338 tests in 14135.916s
16-Jul-2026 02:27:59
16-Jul-2026 02:27:59 OK (skipped=32)
16-Jul-2026 02:27:59 Marking test as passed.
16-Jul-2026 02:27:59 2026-07-16 02:27:51        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 1
16-Jul-2026 02:27:59 2026-07-16 02:27:51        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 2
16-Jul-2026 02:27:59 2026-07-16 02:27:51        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 3
16-Jul-2026 02:27:59 2026-07-16 02:27:54        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal self.signal_stop_service_request() successfully handled by server 1
16-Jul-2026 02:27:59 2026-07-16 02:27:54        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal self.signal_stop_service_request() successfully handled by server 2
16-Jul-2026 02:27:59 2026-07-16 02:27:54        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal self.signal_stop_service_request() successfully handled by server 3
16-Jul-2026 02:27:59 2026-07-16 02:27:57        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        stop service signal received, stopping services...
16-Jul-2026 02:27:59 2026-07-16 02:27:57        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        stop service signal received, stopping services...
16-Jul-2026 02:27:59 2026-07-16 02:27:57        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        stop service signal received, stopping services...
16-Jul-2026 02:27:59 2026-07-16 02:28:00        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-1        MPI command request handler service stopped
16-Jul-2026 02:27:59 2026-07-16 02:28:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Going to finalize MPI environment
16-Jul-2026 02:27:59 2026-07-16 02:28:00        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-2        MPI command request handler service stopped
16-Jul-2026 02:27:59 2026-07-16 02:28:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Going to finalize MPI environment
16-Jul-2026 02:27:59 2026-07-16 02:28:00        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-3        MPI command request handler service stopped
16-Jul-2026 02:28:00 2026-07-16 02:28:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Going to finalize MPI environment
16-Jul-2026 02:28:00 2026-07-16 02:28:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Exiting
16-Jul-2026 02:28:00 2026-07-16 02:28:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Exiting
16-Jul-2026 02:28:00 2026-07-16 02:28:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Exiting
16-Jul-2026 02:28:00 Executor result: 0
16-Jul-2026 02:28:00 Failed tests:[]
16-Jul-2026 02:28:00
16-Jul-2026 02:28:00 Results: [{'testname': 'test_task_tclean', 'runtime': '3:55:53.931119', 'returncode': 0, 'testerr': []}]
16-Jul-2026 02:28:00 Stopping Xvfb.
16-Jul-2026 02:28:00 Stopping framebuffer <Popen: returncode: None args: ['Xvfb', ':133', '-screen', '0', '2048x2048x2...>
16-Jul-2026 02:28:00 Virtual frame buffer deployed at :133 with pid 144 successfully shutdown
16-Jul-2026 02:28:00 Xvfb stopped.
16-Jul-2026 02:28:00 total 812324
16-Jul-2026 02:28:00 -rw-r--r-- 1 casaci    15001 831815372 Jul 15 22:30 casa-6.7.6-11-py3.12.el8.tar.xz
16-Jul-2026 02:28:00 -rw-r--r-- 1 casaci casatest       327 Jul 16 02:28 xUnit-suite-mpi-8.xml
16-Jul-2026 02:28:00 Finished task 'MPI Tasks Tests on EL8' with result: Success
16-Jul-2026 02:28:00 Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
16-Jul-2026 02:28:00 Parsing test results under /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D...
16-Jul-2026 02:28:00 Finished task 'Parse test results' with result: Success
16-Jul-2026 02:28:00
Substituting variable: ${bamboo.capability.hostname} with cvpost135
16-Jul-2026 02:28:00
Substituting variable: ${bamboo.capability.hostname} with cvpost135
16-Jul-2026 02:28:00 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
16-Jul-2026 02:28:00
Beginning to execute external process for build 'CASASPECS - Master Test Casalith 3.12 - Test MPI Task Tclean Many Linux 2.28 Rocky 8.10 Docker #279 (CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279)'
... running command line:
/export/cvpost135-nvme/casa/bamboohome/temp/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279-ScriptBuildTask-3816433444313679204.sh
... in: /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D
bamboo_planRepository_1_branch=master
bamboo_dependency_parent_0=CASASPECS-MASTERBUILDCASALITH-277
bamboo_jira_components=
bamboo_repository_136380562_name=casa6
bamboo_dependency_parent_total=1
bamboo_planRepository_1_branchDisplayName=master
bamboo_repository_revision_number=350d94ec32491983c2ac6a838a3d80a0a5c85748
bamboo_resultsUrl=https://open-bamboo.nrao.edu/browse/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279
bamboo_repository_136380562_git_branch=master
bamboo_build_working_directory=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D
bamboo_planRepository_1_name=casa6
bamboo_buildKey=CASASPECS-MASTERTESTCASALITHPY312-MTTR85D
bamboo_shortPlanName=Master Test Casalith 3.12
bamboo_capability_hostname=cvpost135
bamboo_planRepository_name=casa6
bamboo_python_version=3.12
bamboo_buildNumber=279
bamboo_simpleBuildTimeStamp=20260715144436
bamboo_shortJobName=Test MPI Task Tclean Many Linux 2.28 Rocky 8.10 Docker
bamboo_buildResultsUrl=https://open-bamboo.nrao.edu/browse/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279
bamboo_repository_136380562_branch_name=master
bamboo_planRepository_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_atlutil_user=casaadm
bamboo_ManualBuildTriggerReason_stageName=Default Stage
bamboo_agentId=81985537
bamboo_planName=CASASPECS - Master Test Casalith 3.12
bamboo_shortPlanKey=MASTERTESTCASALITHPY312
bamboo_rocky8_docker_image=casa-docker.nrao.edu:5000/casa-test-rocky-8.10:4
bamboo_shortJobKey=MTTR85D
bamboo_rocky9_docker_image=casa-docker.nrao.edu:5000/casa-test-rocky-9.5:1
bamboo_planRepository_revision=350d94ec32491983c2ac6a838a3d80a0a5c85748
bamboo_repository_previous_revision_number=29b9e60ff5efac7662bc3995bc056d79878deb05
bamboo_buildTimeStamp=2026-07-15T14:44:36.954-04:00
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_3=/export/cvpost135-nvme/casa/bamboohome/temp/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279-ScriptBuildTask-6849466260827321699.sh
bamboo_planRepository_previousRevision=29b9e60ff5efac7662bc3995bc056d79878deb05
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_5=/export/cvpost135-nvme/casa/bamboohome/temp/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279-ScriptBuildTask-1235860722160670932.sh
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_4=/export/cvpost135-nvme/casa/bamboohome/temp/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279-ScriptBuildTask-8801815981284690794.sh
bamboo_repository_git_branch=master
bamboo_buildResultKey=CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279
bamboo_repository_branch_name=master
bamboo_buildPlanName=CASASPECS - Master Test Casalith 3.12 - Test MPI Task Tclean Many Linux 2.28 Rocky 8.10 Docker
bamboo_planRepository_1_revision=350d94ec32491983c2ac6a838a3d80a0a5c85748
bamboo_repository_136380562_revision_number=350d94ec32491983c2ac6a838a3d80a0a5c85748
bamboo_repository_name=casa6
bamboo_buildFailed=false
bamboo_capability_system_docker_executable=/usr/bin/docker
bamboo_planRepository_branch=master
bamboo_agentWorkingDirectory=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir
bamboo_capability_system_git_executable=/usr/bin/git
bamboo_capability_system_jdk_JDK_17=/usr/lib/jvm/java-17-openjdk-17.0.12.0.7-2.el8.x86_64
bamboo_repository_git_username=
bamboo_planRepository_1_previousRevision=29b9e60ff5efac7662bc3995bc056d79878deb05
bamboo_planRepository_branchDisplayName=master
bamboo_planRepository_1_type=bbserver
bamboo_planRepository_branchName=master
bamboo_shortPlanBranchName=
bamboo_capability_system_jdk_JDK=/usr/lib/jvm/java-17-openjdk-17.0.12.0.7-2.el8.x86_64
bamboo_atlutil_password=********
bamboo_plan_storageTag=plan-130056254
bamboo_planRepository_1_username=
bamboo_planRepository_type=bbserver
bamboo_git_cache_directory=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/_git-repositories-cache
bamboo_ManualBuildTriggerReason_userName=awells
bamboo_repository_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_DependencyTriggerReason_triggeringBuildResultKey=CASASPECS-MASTERBUILDCASALITH-277
bamboo_testlist=
bamboo_tmp_directory=/export/cvpost135-nvme/casa/bamboohome/temp
bamboo_working_directory=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTCASALITHPY312-MTTR85D
bamboo_planKey=CASASPECS-MASTERTESTCASALITHPY312
bamboo_RerunBuildTriggerReason_noOfRetries=1
bamboo_planRepository_1_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_capability_system_jdk_JDK_17_0_12__JRE_=/usr/lib/jvm/java-17-openjdk-17.0.12.0.7-2.el8.x86_64
bamboo_planRepository_username=
bamboo_repository_136380562_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_triggerReason_key=com.atlassian.bamboo.plugin.system.triggerReason:RerunBuildTriggerReason
bamboo_repository_136380562_previous_revision_number=29b9e60ff5efac7662bc3995bc056d79878deb05
bamboo_capability_gpu_installed=true
bamboo_repository_136380562_git_username=
bamboo_ubuntu_docker_image=casa-docker.nrao.edu:5000/casa-test-ubuntu-24.04:2
bamboo_planRepository_1_branchName=master
16-Jul-2026 02:28:00 Starting cleanup
16-Jul-2026 02:28:11 test-container
16-Jul-2026 02:28:15 test-container
16-Jul-2026 02:28:15 Error response from daemon: No such container: test-container-cvpost135
16-Jul-2026 02:28:15 Error response from daemon: No such container: test-container-cvpost135
16-Jul-2026 02:28:15 Cleanup complete
16-Jul-2026 02:28:15 Finished task 'Cleanup' with result: Success
16-Jul-2026 02:28:15 Running post build plugin 'Docker Container Cleanup'
16-Jul-2026 02:28:15 Running post build plugin 'NCover Results Collector'
16-Jul-2026 02:28:15 Running post build plugin 'Build Results Label Collector'
16-Jul-2026 02:28:15 Running post build plugin 'Clover Results Collector'
16-Jul-2026 02:28:15 Running post build plugin 'npm Cache Cleanup'
16-Jul-2026 02:28:15 Running post build plugin 'Artifact Copier'
16-Jul-2026 02:28:15 Finalising the build...
16-Jul-2026 02:28:15 Stopping timer.
16-Jul-2026 02:28:15 Build CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279 completed.
15-Jul-2026 22:28:15 Running on server: post build plugin 'Build Hanging Detection Configuration'
15-Jul-2026 22:28:15 Running on server: post build plugin 'NCover Results Collector'
15-Jul-2026 22:28:15 Running on server: post build plugin 'Build Labeller'
15-Jul-2026 22:28:15 Running on server: post build plugin 'Clover Delta Calculator'
15-Jul-2026 22:28:15 Running on server: post build plugin 'Maven Dependencies Postprocessor'
15-Jul-2026 22:28:16 All post build plugins have finished
15-Jul-2026 22:28:16 Generating build results summary...
15-Jul-2026 22:28:16 Saving build results to disk...
15-Jul-2026 22:28:16 Store variable context...
15-Jul-2026 22:28:16 Finished building CASASPECS-MASTERTESTCASALITHPY312-MTTR85D-279.

Older logs

Filename Actions
plan-130056254-MTTR85D-279.log.1 Download or view