Build: #4 was successful

Job: MPI Task Tclean Rocky 8.5 Docker was successful

Build log

The build generated 85,761 lines of output.The output is too long and has been truncated to the last 1,000 lines. Download or view full build log.

15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-1         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-1         Lazy fill is On
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-2         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-2         Lazy fill is On
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-3         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-3         Lazy fill is On
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:45        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        tclean::::casa        ...done
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        tclean::::casa        reloadCFCache, CMD = 1 toolsi.reloadcfcache()
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        tclean::::casa        reloadCFCache, CMD = 2 toolsi.reloadcfcache()
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        tclean::::casa        reloadCFCache, CMD = 3 toolsi.reloadcfcache()
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-1         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-1         Lazy fill is On
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-2         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-2         Lazy fill is On
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-3         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-3         Lazy fill is On
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst.cfcache: 6
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:46        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
15-Feb-2024 08:44:49 2024-02-15 08:44:47        INFO        tclean::::casa        ...done
15-Feb-2024 08:44:49 2024-02-15 08:44:47        INFO        VB2CFBMap::~VB2CFMap[R&D]        Total extra cost of heterogeneous array pointing correction = 0sec.  Total VBs processed =  0. The pointingoffsetsigdev used was = []
15-Feb-2024 08:44:49 2024-02-15 08:44:49        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:44:49 2024-02-15 08:44:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
15-Feb-2024 08:44:49 2024-02-15 08:44:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
15-Feb-2024 08:44:51 2024-02-15 08:44:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
15-Feb-2024 08:44:51 2024-02-15 08:44:50        INFO        tclean::::casa        ...done
15-Feb-2024 08:44:51 2024-02-15 08:44:50        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : mtmfs
15-Feb-2024 08:44:51 2024-02-15 08:44:50        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        tclean::::casa        ...done
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:51 2024-02-15 08:44:51        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-3        Setting up for weights accumulation for term 0 during gridding to compute sensitivity pattern.
15-Feb-2024 08:44:51 2024-02-15 08:44:51        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.
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:51 2024-02-15 08:44:51        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-3        Setting up for weights accumulation for term 1 during gridding to compute sensitivity pattern.
15-Feb-2024 08:44:51 2024-02-15 08:44:51        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.
15-Feb-2024 08:44:51 2024-02-15 08:44:51        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:44:51 2024-02-15 08:44:51        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-3        Setting up for weights accumulation for term 2 during gridding to compute sensitivity pattern.
15-Feb-2024 08:44:51 2024-02-15 08:44:51        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.
15-Feb-2024 08:44:51 2024-02-15 08:44:51        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-2        Setting up for weights accumulation for term 0 during gridding to compute sensitivity pattern.
15-Feb-2024 08:44:51 2024-02-15 08:44:51        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.
15-Feb-2024 08:44:51 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:44:51 2024-02-15 08:44:52        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-1        Setting up for weights accumulation for term 0 during gridding to compute sensitivity pattern.
15-Feb-2024 08:44:51 2024-02-15 08:44:52        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.
15-Feb-2024 08:44:51 2024-02-15 08:44:52        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-2        Setting up for weights accumulation for term 1 during gridding to compute sensitivity pattern.
15-Feb-2024 08:44:51 2024-02-15 08:44:52        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.
15-Feb-2024 08:44:51 2024-02-15 08:44:52        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-2        Setting up for weights accumulation for term 2 during gridding to compute sensitivity pattern.
15-Feb-2024 08:44:55 2024-02-15 08:44:52        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.
15-Feb-2024 08:44:55 2024-02-15 08:44:52        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-1        Setting up for weights accumulation for term 1 during gridding to compute sensitivity pattern.
15-Feb-2024 08:44:55 2024-02-15 08:44:52        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.
15-Feb-2024 08:44:55 2024-02-15 08:44:52        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-1        Setting up for weights accumulation for term 2 during gridding to compute sensitivity pattern.
15-Feb-2024 08:44:55 2024-02-15 08:44:52        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.
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:44:55 2024-02-15 08:44: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)
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8815.97]
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (18.1047,0.363445) (0,0)
15-Feb-2024 08:44:55 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 [8815.9716796875]
15-Feb-2024 08:44:55
15-Feb-2024 08:44:55 2024-02-15 08:44: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)
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [9073.97]
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (20.1085,0.864053) (0,0)
15-Feb-2024 08:44:55 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 [9073.970703125]
15-Feb-2024 08:44:55
15-Feb-2024 08:44:55 2024-02-15 08:44: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)
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [9073.97]
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (19.2334,1.34053) (0,0)
15-Feb-2024 08:44:55 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 [9073.9716796875]
15-Feb-2024 08:44:55
15-Feb-2024 08:44:55 2024-02-15 08:44: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)
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [-0.406142]
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (-1.51957,-0.0772529) (0,0)
15-Feb-2024 08:44:55 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 [-0.40614211559295654297]
15-Feb-2024 08:44:55
15-Feb-2024 08:44:55 2024-02-15 08:44: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)
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [0.802125]
15-Feb-2024 08:44:55 2024-02-15 08:44:52        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-2.1465,-0.0427755) (0,0)
15-Feb-2024 08:44:55 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 [0.80212450027465820312]
15-Feb-2024 08:44:55
15-Feb-2024 08:44:55 2024-02-15 08:44:53        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)
15-Feb-2024 08:44:55 2024-02-15 08:44:53        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [-0.402012]
15-Feb-2024 08:44:55 2024-02-15 08:44:53        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (-1.72903,0.0272276) (0,0)
15-Feb-2024 08:44:55 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 [-0.40201175212860107422]
15-Feb-2024 08:44:55
15-Feb-2024 08:44:55 2024-02-15 08:44:53        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)
15-Feb-2024 08:44:55 2024-02-15 08:44:53        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [235.039]
15-Feb-2024 08:44:55 2024-02-15 08:44:53        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (0.454516,0.00787602) (0,0)
15-Feb-2024 08:44:55 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 [235.039337158203125]
15-Feb-2024 08:44:55
15-Feb-2024 08:44:55 2024-02-15 08:44:53        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)
15-Feb-2024 08:44:55 2024-02-15 08:44:53        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [241.999]
15-Feb-2024 08:44:55 2024-02-15 08:44:53        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (0.485107,0.0321748) (0,0)
15-Feb-2024 08:44:55 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 [241.9991302490234375]
15-Feb-2024 08:44:55
15-Feb-2024 08:44:55 2024-02-15 08:44:53        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)
15-Feb-2024 08:44:55 2024-02-15 08:44:53        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [241.999]
15-Feb-2024 08:44:55 2024-02-15 08:44:53        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (0.494103,0.0202628) (0,0)
15-Feb-2024 08:44:55 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 [241.9988555908203125]
15-Feb-2024 08:44:55
15-Feb-2024 08:44:55 2024-02-15 08:44:54        INFO        tclean::::casa        ...done
15-Feb-2024 08:44:55 2024-02-15 08:44:54        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight 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
15-Feb-2024 08:44:55 2024-02-15 08:44:55        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 2024-02-15 08:44:55        INFO        SIImageStoreMultiTerm::calcSensitivity +        [26963.9, -0.00602937
15-Feb-2024 08:44:55 2024-02-15 08:44:55        INFO        SIImageStoreMultiTerm::calcSensitivity +         -0.00602937, 719.037]
15-Feb-2024 08:44:55 2024-02-15 08:44:55        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:55 2024-02-15 08:44:55        INFO        SIImageStoreMultiTerm::calcSensitivity +        [3.70866e-05, 3.10983e-10
15-Feb-2024 08:44:55 2024-02-15 08:44:55        INFO        SIImageStoreMultiTerm::calcSensitivity +         3.10983e-10, 0.00139075]
15-Feb-2024 08:44:55 2024-02-15 08:44:55        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor0] Theoretical sensitivity (Jy/bm):0.00608988
15-Feb-2024 08:44:55 2024-02-15 08:44:55        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor1] Theoretical sensitivity (Jy/bm):0.0372927
15-Feb-2024 08:44:55 2024-02-15 08:44:55        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.94998GHz.Fractional Bandwidth : 60.0001 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
15-Feb-2024 08:44:57 2024-02-15 08:44:55        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.015067
15-Feb-2024 08:44:57 2024-02-15 08:44:55        INFO        SIImageStore::printBeamSet         Beam : 139.82 arcsec, 129.968 arcsec, -86.7085 deg
15-Feb-2024 08:44:57 2024-02-15 08:44:55        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:44:57 2024-02-15 08:44:56        INFO        tclean::::casa        ...done
15-Feb-2024 08:44:57 2024-02-15 08:44:56        INFO        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]
15-Feb-2024 08:44:57 2024-02-15 08:44:56        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:44:57 2024-02-15 08:44:57        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)
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8838.74]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (24.5476,1.60567) (0,0)
15-Feb-2024 08:44:57 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:57 [8838.73828125]
15-Feb-2024 08:44:57
15-Feb-2024 08:44:57 2024-02-15 08:44:57        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)
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8830.19]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (23.6225,0.65298) (0,0)
15-Feb-2024 08:44:57 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:57 [8830.193359375]
15-Feb-2024 08:44:57
15-Feb-2024 08:44:57 2024-02-15 08:44:57        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)
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8583.62]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (24.4713,0.623138) (0,0)
15-Feb-2024 08:44:57 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:57 [8583.62109375]
15-Feb-2024 08:44:57
15-Feb-2024 08:44:57 2024-02-15 08:44:57        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)
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [-7.52245]
15-Feb-2024 08:44:57 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-2.02907,-0.0262303) (0,0)
15-Feb-2024 08:44:57 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:57 [-7.5224523544311523438]
15-Feb-2024 08:44:57
15-Feb-2024 08:44:57 2024-02-15 08:44:57        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)
15-Feb-2024 08:44:59 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [-8.47029]
15-Feb-2024 08:44:59 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (1.96794,0.0893006) (0,0)
15-Feb-2024 08:44:59 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:59 [-8.4702901840209960938]
15-Feb-2024 08:44:59
15-Feb-2024 08:44:59 2024-02-15 08:44:57        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)
15-Feb-2024 08:44:59 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [-9.40991]
15-Feb-2024 08:44:59 2024-02-15 08:44:57        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (2.06806,0.159504) (0,0)
15-Feb-2024 08:44:59 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:59 [-9.409912109375]
15-Feb-2024 08:44:59
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        tclean::::casa        ...done
15-Feb-2024 08:44:59 2024-02-15 08:44:59        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
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        task_tclean::SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.93131, over full image : 0.93131
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 262144 out of a total of 262144 pixels. [ 100 % ]
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.93131, over full image : 0.93131
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 30, 'cyclethreshold': 0.11672870814800262, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        SDAlgorithmBase::deconvolve         [tst] Run mtmfs minor-cycle | CycleThreshold=0.116729, CycleNiter=30, Gain=0.1
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 80 pixels on each side for minor-cycle updates.
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [256, 256]
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.94998GHz.Fractional Bandwidth : 60.0001 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [1, -1.96829e-07
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -1.96829e-07, 0.0263966]
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 0.93131
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO                [20] Res: 0.125806 Max: 0.0187339 Gain: 0.1 Pos: [122, 195] Scale: 0 Coeffs: 0.0871557  0.649569 
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::mtclean()         Reached stopping threshold at iteration 21. Peak residual 0.113226 (within mask)
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0.8268 (in this run)
15-Feb-2024 08:44:59 2024-02-15 08:44:59        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 0.8268  [1]: 0.0352555
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->21 [21], model=0->0.8268, peakres=0.93131->0.113222, Reached cyclethreshold.
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 21 iterations.
15-Feb-2024 08:45:00 2024-02-15 08:44:59        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]
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
15-Feb-2024 08:45:00 2024-02-15 08:44:59        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-1        Loaded "/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.weight.tt0"
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-2        Loaded "/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.weight.tt0"
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-3        Loaded "/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.weight.tt0"
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-1        Loaded "/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.weight.tt1"
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-2        Loaded "/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.weight.tt1"
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::CFCache2::loadWtImage::MPIServer-3        Loaded "/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.weight.tt1"
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:45:00 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:45:00 2024-02-15 08:45:00        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-1        Time degrid 0.16
15-Feb-2024 08:45:01 2024-02-15 08:45:00        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-1        Time degrid 0.06
15-Feb-2024 08:45:01 2024-02-15 08:45:00        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)
15-Feb-2024 08:45:01 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8830.19]
15-Feb-2024 08:45:01 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (3.45109,0.0280062) (0,0)
15-Feb-2024 08:45:01 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:01 [8830.193359375]
15-Feb-2024 08:45:01
15-Feb-2024 08:45:01 2024-02-15 08:45:00        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-3        Time degrid 0.12
15-Feb-2024 08:45:01 2024-02-15 08:45:00        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-3        Time degrid 0.08
15-Feb-2024 08:45:01 2024-02-15 08:45:00        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-2        Time degrid 0.13
15-Feb-2024 08:45:01 2024-02-15 08:45:00        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-2        Time degrid 0.1
15-Feb-2024 08:45:01 2024-02-15 08:45:00        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)
15-Feb-2024 08:45:01 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8583.62]
15-Feb-2024 08:45:01 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (3.59507,0.106884) (0,0)
15-Feb-2024 08:45:01 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:01 [8583.62109375]
15-Feb-2024 08:45:01
15-Feb-2024 08:45:01 2024-02-15 08:45:00        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)
15-Feb-2024 08:45:01 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8838.74]
15-Feb-2024 08:45:01 2024-02-15 08:45:00        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (3.56723,0.306554) (0,0)
15-Feb-2024 08:45:01 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:01 [8838.73828125]
15-Feb-2024 08:45:01
15-Feb-2024 08:45:01 2024-02-15 08:45:01        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)
15-Feb-2024 08:45:01 2024-02-15 08:45:01        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [-8.47029]
15-Feb-2024 08:45:01 2024-02-15 08:45:01        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (0.293382,-0.0470198) (0,0)
15-Feb-2024 08:45:01 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:01 [-8.4702901840209960938]
15-Feb-2024 08:45:01
15-Feb-2024 08:45:01 2024-02-15 08:45:01        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)
15-Feb-2024 08:45:01 2024-02-15 08:45:01        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [-7.52245]
15-Feb-2024 08:45:01 2024-02-15 08:45:01        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-0.292432,-0.0043994) (0,0)
15-Feb-2024 08:45:01 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:01 [-7.5224523544311523438]
15-Feb-2024 08:45:01
15-Feb-2024 08:45:01 2024-02-15 08:45:01        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)
15-Feb-2024 08:45:01 2024-02-15 08:45:01        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [-9.40991]
15-Feb-2024 08:45:01 2024-02-15 08:45:01        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (0.304551,0.025521) (0,0)
15-Feb-2024 08:45:01 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:01 [-9.409912109375]
15-Feb-2024 08:45:01
15-Feb-2024 08:45:01 2024-02-15 08:45:01        INFO        tclean::::casa        ...done
15-Feb-2024 08:45:01 2024-02-15 08:45:01        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
15-Feb-2024 08:45:01 2024-02-15 08:45:01        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:01 2024-02-15 08:45:01        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:01 2024-02-15 08:45:01        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:01 2024-02-15 08:45:01        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:01 2024-02-15 08:45:02        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.136883, over full image : 0.136883
15-Feb-2024 08:45:01 2024-02-15 08:45:02        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 9, 'cyclethreshold': 0.017156723886728287, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
15-Feb-2024 08:45:01 2024-02-15 08:45:02        INFO        SDAlgorithmBase::deconvolve         [tst] Run mtmfs minor-cycle | CycleThreshold=0.0171567, CycleNiter=9, Gain=0.1
15-Feb-2024 08:45:01 2024-02-15 08:45:02        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:01 2024-02-15 08:45:02        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:01 2024-02-15 08:45:02        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:01 2024-02-15 08:45:02        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:01 2024-02-15 08:45:02        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.94998GHz.Fractional Bandwidth : 60.0001 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 0.136883
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0.910652 (in this run)
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 0.910652  [1]: 0.0487351
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->9 [9], model=0.8268->0.910652, peakres=0.136883->0.0530315, Reached cycleniter.
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 30 iterations.
15-Feb-2024 08:45:02 2024-02-15 08:45:02        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]
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 3 -------------------------------------
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 3 -------------------------------------
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 3 -------------------------------------
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:02 2024-02-15 08:45:02        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:45:02 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:45:02 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:45:02 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 1  for spw :0
15-Feb-2024 08:45:02 2024-02-15 08:45:03        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-1        Time degrid 0.13
15-Feb-2024 08:45:02 2024-02-15 08:45:03        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-1        Time degrid 0.1
15-Feb-2024 08:45:02 2024-02-15 08:45:03        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)
15-Feb-2024 08:45:02 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8830.19]
15-Feb-2024 08:45:02 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (1.40217,-0.00871974) (0,0)
15-Feb-2024 08:45:04 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:04 [8830.193359375]
15-Feb-2024 08:45:04
15-Feb-2024 08:45:04 2024-02-15 08:45:03        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-2        Time degrid 0.12
15-Feb-2024 08:45:04 2024-02-15 08:45:03        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-2        Time degrid 0.08
15-Feb-2024 08:45:04 2024-02-15 08:45:03        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-3        Time degrid 0.15
15-Feb-2024 08:45:04 2024-02-15 08:45:03        WARN        MPICommandServer::command_request_handler_service::FTMachine::initMaps::MPIServer-3        Time degrid 0.06
15-Feb-2024 08:45:04 2024-02-15 08:45:03        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)
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8838.74]
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (1.39209,0.161258) (0,0)
15-Feb-2024 08:45:04 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:04 [8838.73828125]
15-Feb-2024 08:45:04
15-Feb-2024 08:45:04 2024-02-15 08:45:03        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)
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8583.62]
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (1.44659,0.0437157) (0,0)
15-Feb-2024 08:45:04 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:04 [8583.62109375]
15-Feb-2024 08:45:04
15-Feb-2024 08:45:04 2024-02-15 08:45:03        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)
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [-9.40991]
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (0.114298,-0.0140878) (0,0)
15-Feb-2024 08:45:04 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:04 [-9.409912109375]
15-Feb-2024 08:45:04
15-Feb-2024 08:45:04 2024-02-15 08:45:03        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)
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [-8.47029]
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (0.119333,-0.0197033) (0,0)
15-Feb-2024 08:45:04 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:04 [-8.4702901840209960938]
15-Feb-2024 08:45:04
15-Feb-2024 08:45:04 2024-02-15 08:45:03        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)
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [-7.52245]
15-Feb-2024 08:45:04 2024-02-15 08:45:03        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-0.121316,-0.00238232) (0,0)
15-Feb-2024 08:45:04 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:04 [-7.5224523544311523438]
15-Feb-2024 08:45:04
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        tclean::::casa        ...done
15-Feb-2024 08:45:04 2024-02-15 08:45:04        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
15-Feb-2024 08:45:04 2024-02-15 08:45:04        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:04 2024-02-15 08:45:04        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:04 2024-02-15 08:45:04        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:04 2024-02-15 08:45:04        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0555073, over full image : 0.0555073
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        tclean::::casa        Reached global stopping criterion : iteration limit
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        tclean::::casa        getSummary call: fullsummary=False
15-Feb-2024 08:45:04 2024-02-15 08:45:04        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7338, 180, 40.7338]) and current imaging run ([299.869, 40.7337, 180, 40.7337]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
15-Feb-2024 08:45:04 2024-02-15 08:45:04        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        SIImageStore::restore         Beam for chan : 0 : 139.82 arcsec, 129.968 arcsec, -86.7085 deg
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        SIImageStore::restore         Beam for chan : 0 : 139.82 arcsec, 129.968 arcsec, -86.7085 deg
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.00555073 Jy/beam
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /home/casatest/work/test_task_tclean/tst.alpha.error
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        SDAlgorithmMSMFS::restore         Restored images :
15-Feb-2024 08:45:04 2024-02-15 08:45:04        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7337, 180, 40.7337]) and current imaging run ([299.869, 40.7338, 180, 40.7338]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt0  (model=/home/casatest/work/test_task_tclean/tst.model.tt0)
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt1  (model=/home/casatest/work/test_task_tclean/tst.model.tt1)
15-Feb-2024 08:45:04 2024-02-15 08:45:04        INFO        SDAlgorithmMSMFS::restore         
15-Feb-2024 08:45:29 2024-02-15 08:45:05        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-2        Total extra cost of heterogeneous array pointing correction = 0.28sec.  Total VBs processed =  72852. The pointingoffsetsigdev used was = []
15-Feb-2024 08:45:29 2024-02-15 08:45:05        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-3        Total extra cost of heterogeneous array pointing correction = 0.21sec.  Total VBs processed =  72791. The pointingoffsetsigdev used was = []
15-Feb-2024 08:45:29 2024-02-15 08:45:05        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-1        Total extra cost of heterogeneous array pointing correction = 0.31sec.  Total VBs processed =  72865. The pointingoffsetsigdev used was = []
15-Feb-2024 08:45:29 2024-02-15 08:45:22        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:45:29 2024-02-15 08:45:23        INFO        ::casa::MPIServer-2        node 2 has completed its job
15-Feb-2024 08:45:29 2024-02-15 08:45:23        INFO        ::casa::MPIServer-3        node 3 has completed its job
15-Feb-2024 08:45:29 2024-02-15 08:45:23        INFO        ::casa::MPIServer-1        node 1 has completed its job
15-Feb-2024 08:45:29 2024-02-15 08:45:23        INFO        tclean::::casa        ...done
15-Feb-2024 08:45:29 2024-02-15 08:45:23        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
15-Feb-2024 08:45:29 2024-02-15 08:45:23        INFO        tclean::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.sumwt.tt1', 'tst.weight.tt2', 'tst.residual.tt1', 'tst.image.tt0', 'tst.sumwt.tt0', 'tst.sumwt.tt2', 'tst.pb.tt0', 'tst.mask', 'tst.image.tt1', 'tst.psf.tt2', 'tst.weight.tt1', 'tst.model.tt1', 'tst.alpha.error', 'tst.residual.tt0', 'tst.psf.tt0', 'tst.psf.tt1', 'tst.weight.tt0', 'tst.model.tt0', 'tst.alpha']
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        tclean::::casa        Task tclean complete. Start time: 2024-02-15 08:43:08.642999 End time: 2024-02-15 08:45:23.838010
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        tclean::::casa        ##### End Task: tclean               #####
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        tclean::::casa        ##########################################
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        name::        No table opened.
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        showcache::        The Table Cache is empty.
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        tclean::::casa        [ checkall ]
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.image.tt0', 'tst.psf.tt0', 'tst.weight.tt0'] =  [True, True, True] ( Pass : should all be True )
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        tclean::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 0.9574441909790039 at [256, 256, 0, 0] ( Pass : should be 0.96 , Epsilon: 0.05)
15-Feb-2024 08:45:29 2024-02-15 08:45:24        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)
15-Feb-2024 08:45:29 2024-02-15 08:45:24        INFO        tclean::::casa+        [ check_pix_val ] tst.alpha : Value is -0.0044462960213422775 at [256, 256, 0, 0] ( Pass : should be 0.0 , Epsilon: 0.05)
15-Feb-2024 08:45:29 .2024-02-15 08:45:25        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
15-Feb-2024 08:45:29 2024-02-15 08:45:25        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
15-Feb-2024 08:45:29 2024-02-15 08:45:25        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
15-Feb-2024 08:45:29 2024-02-15 08:45:27        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
15-Feb-2024 08:45:29 2024-02-15 08:45:27        INFO        tclean::::casa        ##########################################
15-Feb-2024 08:45:29 2024-02-15 08:45:27        INFO        tclean::::casa        ##### Begin Task: tclean             #####
15-Feb-2024 08:45:29 2024-02-15 08:45:27        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 )
15-Feb-2024 08:45:29 2024-02-15 08:45:27        INFO        tclean::::casa        Verifying Input Parameters
15-Feb-2024 08:45:29 2024-02-15 08:45:28        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
15-Feb-2024 08:45:29 2024-02-15 08:45:28        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
15-Feb-2024 08:45:29 2024-02-15 08:45:28        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
15-Feb-2024 08:45:29 2024-02-15 08:45:30        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
15-Feb-2024 08:45:29 2024-02-15 08:45:30        INFO        SynthesisUtilMethods::continuumDataPartition         
15-Feb-2024 08:45:29 2024-02-15 08:45:30        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 10065 15093 [P][M]: 0:2 ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093
15-Feb-2024 08:45:29 2024-02-15 08:45:30        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': ''}}}
15-Feb-2024 08:45:29 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::selectData         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
15-Feb-2024 08:45:29 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::selectData           NRows selected : 15093
15-Feb-2024 08:45:29 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.awp] :
15-Feb-2024 08:45:29 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::defineImage         Impars : start
15-Feb-2024 08:45:29 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::defineImage         Shape : [2048, 2048, 1, 1]Spectral : [9.99981e+08] at [0] with increment [1.00218e+06]
15-Feb-2024 08:45:29 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.awp] with ftmachine : awprojectft
15-Feb-2024 08:45:33 2024-02-15 08:45:30        INFO        CFCache::setLazyFill         Lazy fill is On
15-Feb-2024 08:45:33 2024-02-15 08:45:30        INFO        CFCache2::fillCFSFromDisk        No. of CFS* found in tst_use_awp.cf: 0
15-Feb-2024 08:45:33 2024-02-15 08:45:30        INFO        CFCache2::fillCFSFromDisk        No. of WTCFS* found in tst_use_awp.cf: 0
15-Feb-2024 08:45:33 2024-02-15 08:45:30        INFO        AWProjectFT2::setPAIncrement[R&D]        Setting PA increment to 360 deg
15-Feb-2024 08:45:33 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::createAWPFTMachine         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:33 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::createAWPFTMachine +        [0, 1e+09, 1e+09, 1e+06]
15-Feb-2024 08:45:33 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::createAWPFTMachine +         to AWP FTM.
15-Feb-2024 08:45:33 2024-02-15 08:45:30        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 1.397 GB. Available mem.: 388.5 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
15-Feb-2024 08:45:33 2024-02-15 08:45:30        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:45:33 2024-02-15 08:45:31        INFO        tclean::::casa        ...done
15-Feb-2024 08:45:33 2024-02-15 08:45:31        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:45:33 2024-02-15 08:45:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
15-Feb-2024 08:45:33 2024-02-15 08:45:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
15-Feb-2024 08:45:33 2024-02-15 08:45:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
15-Feb-2024 08:45:33 2024-02-15 08:45:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064 |   NRows selected : 5032
15-Feb-2024 08:45:33 2024-02-15 08:45:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093 |   NRows selected : 5029
15-Feb-2024 08:45:33 2024-02-15 08:45:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 5032 |   NRows selected : 5032
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        tclean::::casa        ...done
15-Feb-2024 08:45:33 2024-02-15 08:45:32        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] :
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars : start
15-Feb-2024 08:45:33 2024-02-15 08:45:32        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]
15-Feb-2024 08:45:33 2024-02-15 08:45:32        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
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-1         Lazy fill is On
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst_use_awp.cf: 0
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst_use_awp.cf: 0
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::setPAIncrement[R&D]::MPIServer-1        Setting PA increment to 360 deg
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-1         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-1 +        [0, 1e+09, 1e+09, 1e+06]
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-1 +         to AWP FTM.
15-Feb-2024 08:45:33 2024-02-15 08:45:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 1.397 GB. Available mem.: 388.5 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
15-Feb-2024 08:45:33 2024-02-15 08:45:33        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] :
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars : start
15-Feb-2024 08:45:33 2024-02-15 08:45:33        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]
15-Feb-2024 08:45:33 2024-02-15 08:45:33        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
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-2         Lazy fill is On
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst_use_awp.cf: 0
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst_use_awp.cf: 0
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::setPAIncrement[R&D]::MPIServer-2        Setting PA increment to 360 deg
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-2         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-2 +        [0, 1e+09, 1e+09, 1e+06]
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-2 +         to AWP FTM.
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 1.397 GB. Available mem.: 388.5 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:45:33 2024-02-15 08:45:33        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] :
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars : start
15-Feb-2024 08:45:33 2024-02-15 08:45:33        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]
15-Feb-2024 08:45:33 2024-02-15 08:45:33        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
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-3         Lazy fill is On
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst_use_awp.cf: 0
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst_use_awp.cf: 0
15-Feb-2024 08:45:33 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::setPAIncrement[R&D]::MPIServer-3        Setting PA increment to 360 deg
15-Feb-2024 08:48:15 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-3         Sending frequency selection information Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:48:15 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-3 +        [0, 1e+09, 1e+09, 1e+06]
15-Feb-2024 08:48:15 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::createAWPFTMachine::MPIServer-3 +         to AWP FTM.
15-Feb-2024 08:48:15 2024-02-15 08:45:33        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 1.397 GB. Available mem.: 388.5 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        SynthesisImagerVi2::dryGridding         ---------------------------------------------------- Dry Gridding ---------------------------------------------
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWProjectFT2::init[R&D]        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWProjectFT2::initializeToSky[R&D]        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:48:15 2024-02-15 08:45:34        WARN        SynthesisImagerVi2::dryGridding (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 2949)        Making a "blank" CFCache (without the A-Term)
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        VB2CFBMap::makeVBRow2CFBMap[R&D]        CFs not cached for -64.1134 deg, dPA = 360 Field ID = 0 Ant1Type, Ant2Type = 0,0
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWConvFunc2::makeConvFunction[R&D]        Making a new convolution function for PA=-64.1134deg for field ID 0
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWConvFunc2::makeConvFunction[R&D]        Using 16 planes for W-projection
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWConvFunc2::makeConvFunction[R&D]        Estimating maximum possible W = 5156.62 (wavelengths)
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWConvFunc2::makeConvFunction[R&D]        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWConvFunc2::makeConvFunction[R&D]        Making 16 w plane(s).
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWConvFunc2::makeConvFunction[R&D]        Making 1 frequency plane(s).
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWConvFunc2::makeConvFunction[R&D]        CFB Freq. axis [N, Min, Max, Incr. (GHz)]: 1 0.999981 0.999981 0.1
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        AWConvFunc2::makeConvFunction[R&D]        Making empty CFs for baseline type 0
15-Feb-2024 08:48:15 2024-02-15 08:45:34        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
15-Feb-2024 08:48:15 2024-02-15 08:45:34        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
15-Feb-2024 08:48:15 2024-02-15 08:45:34        INFO        CFStore2::makePersistent(pa)[R&D]        Saving CFStore of shape [1, 16, 2] for PA = -64.1134 BaselineType(antType1, antType2) = (0,0)
15-Feb-2024 08:48:15 2024-02-15 08:45:35        INFO        CFStore2::makePersistent(pa)[R&D]        Saving CFStore of shape [1, 16, 2] for PA = -64.1134 BaselineType(antType1, antType2) = (0,0)
15-Feb-2024 08:48:15 2024-02-15 08:45:36        INFO        AWProjectFT2::findConvFunction[R&D]        Convolution function memory footprint:0 KB out of a maximum of 515213 MB
15-Feb-2024 08:48:15 2024-02-15 08:45:36        INFO        SynthesisImagerVi2::fillCFCache         ---------------------------------------------------- fillCFCache ---------------------------------------------
15-Feb-2024 08:48:15 2024-02-15 08:45:36        WARN        SynthesisImagerVi2::fillCFCache (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 3033)        Re-loading the "blank" CFCache for filling
15-Feb-2024 08:48:15 2024-02-15 08:45:37        INFO        CFCache2::fillCFListFromDisk        Loading misc info from CFs
15-Feb-2024 08:48:15 2024-02-15 08:45:37        INFO        CFCache2::fillCFListFromDisk        Loading misc info from CFs
15-Feb-2024 08:48:15 2024-02-15 08:45:37        WARN        AWConvFunc2::makeConvFunction2[R&D]        Filling CFs for baseline type 0, PA slot 0
15-Feb-2024 08:48:15 2024-02-15 08:45:41        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:0): CF Support: 4 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:45:46        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:22.9183): CF Support: 5 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:45:51        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:91.6732): CF Support: 5 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:45:56        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:206.265): CF Support: 5 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:01        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:366.693): CF Support: 6 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:06        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:572.958): CF Support: 7 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:11        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:825.059): CF Support: 9 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:16        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:1123): CF Support: 10 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:20        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:1466.77): CF Support: 12 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:25        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:1856.38): CF Support: 14 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:30        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:2291.83): CF Support: 17 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:35        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:2773.12): CF Support: 19 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:40        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:3300.24): CF Support: 22 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:45        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:3873.19): CF Support: 26 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:50        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:4491.99): CF Support: 29 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:55        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:0,C:0.999981,W:5156.62): CF Support: 33 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:46:59        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:0): CF Support: 4 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:04        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:22.9183): CF Support: 5 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:09        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:91.6732): CF Support: 5 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:14        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:206.265): CF Support: 5 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:19        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:366.693): CF Support: 6 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:24        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:572.958): CF Support: 7 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:29        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:825.059): CF Support: 9 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:34        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:1123): CF Support: 10 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:39        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:1466.77): CF Support: 12 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:44        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:1856.38): CF Support: 14 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:49        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:2291.83): CF Support: 17 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:53        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:2773.12): CF Support: 19 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:47:58        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:3300.24): CF Support: 22 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:48:03        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:3873.19): CF Support: 26 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:48:08        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:4491.99): CF Support: 29 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:48:13        INFO        AWConvFunc2::fillConvFuncBuffer2[R&D]         CF(M:15,C:0.999981,W:5156.62): CF Support: 33 (6) pixels
15-Feb-2024 08:48:15 2024-02-15 08:48:13        INFO        CFStore2.2::makePersistent[R&D]        Writing CFStore(0,0) of shape [1, 16, 2]
15-Feb-2024 08:48:15 2024-02-15 08:48:14        INFO        CFStore2.2::makePersistent[R&D]        Writing CFStore(0,0) of shape [1, 16, 2]
15-Feb-2024 08:48:15 2024-02-15 08:48:16        INFO        tclean::::casa        reloadCFCache, CMD = 1 toolsi.reloadcfcache()
15-Feb-2024 08:48:15 2024-02-15 08:48:16        INFO        tclean::::casa        reloadCFCache, CMD = 2 toolsi.reloadcfcache()
15-Feb-2024 08:48:15 2024-02-15 08:48:16        INFO        tclean::::casa        reloadCFCache, CMD = 3 toolsi.reloadcfcache()
15-Feb-2024 08:48:15 2024-02-15 08:48:16        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-1         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-1         Lazy fill is On
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-2         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-2         Lazy fill is On
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-3         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-3         Lazy fill is On
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:16        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        tclean::::casa        reloadCFCache, CMD = 1 toolsi.reloadcfcache()
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        tclean::::casa        reloadCFCache, CMD = 2 toolsi.reloadcfcache()
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        tclean::::casa        reloadCFCache, CMD = 3 toolsi.reloadcfcache()
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-3         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-3         Lazy fill is On
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of CFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-1         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-1         Lazy fill is On
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of CFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::reloadCFCache::MPIServer-2         -------------------------------------------- Re-load CFCache ---------------------------------------------
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache::setLazyFill::MPIServer-2         Lazy fill is On
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of CFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-3        No. of WTCFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-3        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-1        No. of WTCFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFSFromDisk::MPIServer-2        No. of WTCFS* found in tst_use_awp.cf: 32
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-1        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:17        INFO        MPICommandServer::command_request_handler_service::CFCache2::fillCFListFromDisk::MPIServer-2        Loading misc info from CFs
15-Feb-2024 08:48:22 2024-02-15 08:48:18        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:22 2024-02-15 08:48:18        INFO        VB2CFBMap::~VB2CFMap[R&D]        Total extra cost of heterogeneous array pointing correction = 0sec.  Total VBs processed =  0. The pointingoffsetsigdev used was = []
15-Feb-2024 08:48:22 2024-02-15 08:48:20        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:22 2024-02-15 08:48:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
15-Feb-2024 08:48:22 2024-02-15 08:48:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
15-Feb-2024 08:48:22 2024-02-15 08:48:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
15-Feb-2024 08:48:22 2024-02-15 08:48:21        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:22 2024-02-15 08:48:21        INFO        tclean::::casa        Gathering/Merging/Scattering Weight Density for PSF generation
15-Feb-2024 08:48:22 2024-02-15 08:48:21        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:22 2024-02-15 08:48:22        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:22 2024-02-15 08:48:22        INFO        tclean::::casa        ******************************************************
15-Feb-2024 08:48:22 2024-02-15 08:48:22        INFO        tclean::::casa         gather and scatter now
15-Feb-2024 08:48:22 2024-02-15 08:48:22        INFO        tclean::::casa        ******************************************************
15-Feb-2024 08:48:22 2024-02-15 08:48:22        INFO        SynthesisNormalizer::gatherImages         Gather  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
15-Feb-2024 08:48:29 2024-02-15 08:48:22        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]
15-Feb-2024 08:48:29 2024-02-15 08:48:22        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:29 2024-02-15 08:48:23        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:29 2024-02-15 08:48:23        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.awp] : hogbom
15-Feb-2024 08:48:29 2024-02-15 08:48:23        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:48:29 2024-02-15 08:48:24        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:48:29 2024-02-15 08:48:25        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-3        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:48:29 2024-02-15 08:48:25        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-3        Setting up for weights accumulation during gridding to compute sensitivity pattern.
15-Feb-2024 08:48:29 2024-02-15 08:48:25        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.
15-Feb-2024 08:48:29 2024-02-15 08:48:25        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-2        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:48:29 2024-02-15 08:48:25        INFO        MPICommandServer::command_request_handler_service::PhaseGrad::computeAntennaPointingGrad::MPIServer-1        Computing Phase Grad for Field : 0  for spw :0
15-Feb-2024 08:48:29 2024-02-15 08:48:25        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-2        Setting up for weights accumulation during gridding to compute sensitivity pattern.
15-Feb-2024 08:48:29 2024-02-15 08:48:25        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.
15-Feb-2024 08:48:29 2024-02-15 08:48:25        WARN        MPICommandServer::command_request_handler_service::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]::MPIServer-1        Setting up for weights accumulation during gridding to compute sensitivity pattern.
15-Feb-2024 08:48:29 2024-02-15 08:48:25        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.
15-Feb-2024 08:48:29 2024-02-15 08:48:26        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)
15-Feb-2024 08:48:29 2024-02-15 08:48:26        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8474.84]
15-Feb-2024 08:48:29 2024-02-15 08:48:26        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (0.992538,2.75834e-08) (0,0)
15-Feb-2024 08:48:29 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:48:29 [8474.837890625]
15-Feb-2024 08:48:29
15-Feb-2024 08:48:29 2024-02-15 08:48:26        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)
15-Feb-2024 08:48:29 2024-02-15 08:48:26        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8258.9]
15-Feb-2024 08:48:29 2024-02-15 08:48:26        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (1.39034,-2.92298e-08) (0,0)
15-Feb-2024 08:48:29 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:48:29 [8258.8974609375]
15-Feb-2024 08:48:29
15-Feb-2024 08:48:29 2024-02-15 08:48:26        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)
15-Feb-2024 08:48:29 2024-02-15 08:48:26        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8234.25]
15-Feb-2024 08:48:29 2024-02-15 08:48:26        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (1.39154,-7.1218e-08) (0,0)
15-Feb-2024 08:48:29 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:48:29 [8234.251953125]
15-Feb-2024 08:48:29
15-Feb-2024 08:48:29 2024-02-15 08:48:27        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:29 2024-02-15 08:48:27        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
15-Feb-2024 08:48:29 2024-02-15 08:48:28        INFO        SIImageStore::calcSensitivity         [tst.awp] Theoretical sensitivity (Jy/bm):0.00632861
15-Feb-2024 08:48:29 2024-02-15 08:48:28        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.041907
15-Feb-2024 08:48:29 2024-02-15 08:48:28        INFO        SIImageStore::printBeamSet         Beam : 85.3879 arcsec, 71.2449 arcsec, -89.0476 deg
15-Feb-2024 08:48:29 2024-02-15 08:48:28        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:29 2024-02-15 08:48:29        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:29 2024-02-15 08:48:29        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]
15-Feb-2024 08:48:29 2024-02-15 08:48:29        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp.model to /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n1.model
15-Feb-2024 08:48:29 2024-02-15 08:48:29        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp.model to /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n2.model
15-Feb-2024 08:48:29 2024-02-15 08:48:29        INFO        SIImageStore::setModelImageOne         Copying input model tst.awp.model to /home/casatest/work/test_task_tclean/tst.awp.workdirectory/tst.awp.n3.model
15-Feb-2024 08:48:29 2024-02-15 08:48:29        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
15-Feb-2024 08:48:29 2024-02-15 08:48:29        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:29 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-3        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-3        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-2        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-2        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::init[R&D]::MPIServer-1        Using CPUResampler visibility resampler (a.k.a. "gridder/degridder")
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::initializeToSky[R&D]::MPIServer-1        Computed maps using FTMachine::initMaps. polMap = [0, 0]
15-Feb-2024 08:48:46 2024-02-15 08:48:30        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)
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+        [8235.06]
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-3+         (-0.996398,0.392974) (0,0)
15-Feb-2024 08:48:46 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:48:46 [8235.0595703125]
15-Feb-2024 08:48:46
15-Feb-2024 08:48:46 2024-02-15 08:48:30        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)
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+        [8475.52]
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-2+         (-0.4708,-1.04899) (0,0)
15-Feb-2024 08:48:46 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:48:46 [8475.517578125]
15-Feb-2024 08:48:46
15-Feb-2024 08:48:46 2024-02-15 08:48:30        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)
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+        [8259.7]
15-Feb-2024 08:48:46 2024-02-15 08:48:30        INFO        MPICommandServer::command_request_handler_service::AWProjectFT2::getImage[R&D]::MPIServer-1+         (-1.08331,0.295812) (0,0)
15-Feb-2024 08:48:46 Sum of weights: Axis Lengths: [1, 1]  (NB: Matrix in Row/Column order)
15-Feb-2024 08:48:46 [8259.69921875]
15-Feb-2024 08:48:46
15-Feb-2024 08:48:46 2024-02-15 08:48:31        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:46 2024-02-15 08:48:31        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
15-Feb-2024 08:48:46 2024-02-15 08:48:31        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7226, 180, 40.7226]) and current imaging run ([299.869, 40.7226, 180, 40.7226]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:48:46 2024-02-15 08:48:32        WARN        SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7226, 180, 40.7226]) and current imaging run ([299.869, 40.7226, 180, 40.7226]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:48:46 2024-02-15 08:48:32        INFO        task_tclean::SDAlgorithmBase::restore         [tst.awp] : Restoring model image.
15-Feb-2024 08:48:46 2024-02-15 08:48:32        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
15-Feb-2024 08:48:46 2024-02-15 08:48:32        WARN        task_tclean::SIImageStore::Open existing Images (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 582)         Mismatch in Csys latpoles between existing image on disk ([299.869, 40.7226, 180, 40.7226]) and current imaging run ([299.869, 40.7226, 180, 40.7226]) : The DirectionCoordinates have differing latpoles -- Resetting to match image on disk
15-Feb-2024 08:48:46 2024-02-15 08:48:32        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 85.3879 arcsec, 71.2449 arcsec, -89.0476 deg
15-Feb-2024 08:48:46 2024-02-15 08:48:32        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-1        Total extra cost of heterogeneous array pointing correction = 0.05sec.  Total VBs processed =  15798. The pointingoffsetsigdev used was = []
15-Feb-2024 08:48:46 2024-02-15 08:48:32        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-3        Total extra cost of heterogeneous array pointing correction = 0.07sec.  Total VBs processed =  15317. The pointingoffsetsigdev used was = []
15-Feb-2024 08:48:46 2024-02-15 08:48:32        INFO        MPICommandServer::command_request_handler_service::VB2CFBMap::~VB2CFMap[R&D]::MPIServer-2        Total extra cost of heterogeneous array pointing correction = 0.03sec.  Total VBs processed =  15562. The pointingoffsetsigdev used was = []
15-Feb-2024 08:48:46 2024-02-15 08:48:42        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:46 2024-02-15 08:48:42        INFO        ::casa::MPIServer-3        node 3 has completed its job
15-Feb-2024 08:48:46 2024-02-15 08:48:42        INFO        ::casa::MPIServer-2        node 2 has completed its job
15-Feb-2024 08:48:46 2024-02-15 08:48:43        INFO        ::casa::MPIServer-1        node 1 has completed its job
15-Feb-2024 08:48:46 2024-02-15 08:48:43        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:46 2024-02-15 08:48:43        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
15-Feb-2024 08:48:46 2024-02-15 08:48:43        INFO        tclean::::casa        Searching for images with prefix 'tst.awp'... Found these, writing history into them: ['tst.awp.residual', 'tst.awp.gridwt', 'tst.awp.model', 'tst.awp.weight', 'tst.awp.sumwt', 'tst.awp.image', 'tst.awp.psf', 'tst.awp.pb']
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        tclean::::casa        Task tclean complete. Start time: 2024-02-15 08:45:26.823872 End time: 2024-02-15 08:48:43.529007
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        tclean::::casa        ##### End Task: tclean               #####
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        tclean::::casa        ##########################################
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        name::        No table opened.
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        showcache::        The Table Cache is empty.
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        tclean::::casa        [ checkall ]
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.awp.image'] =  [True] ( Pass : should all be True )
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
15-Feb-2024 08:48:46 2024-02-15 08:48:44        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)
15-Feb-2024 08:48:46 2024-02-15 08:48:44        INFO        tclean::::casa+        [ check_pix_val ] tst.awp.image : Value is 0.9958506226539612 at [1158, 1384, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
15-Feb-2024 08:48:46 .s2024-02-15 08:48:44        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
15-Feb-2024 08:48:54 2024-02-15 08:48:47        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
15-Feb-2024 08:48:54 2024-02-15 08:48:47        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
15-Feb-2024 08:48:54 2024-02-15 08:48:48        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
15-Feb-2024 08:48:54 2024-02-15 08:48:48        INFO        tclean::::casa        ##########################################
15-Feb-2024 08:48:54 2024-02-15 08:48:48        INFO        tclean::::casa        ##### Begin Task: tclean             #####
15-Feb-2024 08:48:54 2024-02-15 08:48:48        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 )
15-Feb-2024 08:48:54 2024-02-15 08:48:48        INFO        tclean::::casa        Verifying Input Parameters
15-Feb-2024 08:48:54 2024-02-15 08:48:50        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
15-Feb-2024 08:48:54 2024-02-15 08:48:50        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
15-Feb-2024 08:48:54 2024-02-15 08:48:50        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        SynthesisUtilMethods::continuumDataPartition         
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 10065 15093 [P][M]: 0:2 ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093
15-Feb-2024 08:48:54 2024-02-15 08:48:51        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': ''}}}
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        SynthesisImagerVi2::selectData         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        SynthesisImagerVi2::selectData           NRows selected : 15093
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.wyes] :
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        SynthesisImagerVi2::defineImage         Impars : start
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        SynthesisImagerVi2::defineImage         Shape : [2048, 2048, 1, 1]Spectral : [9.99981e+08] at [0] with increment [1.00218e+06]
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.wyes] with ftmachine : wprojectft
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 1.397 GB. Available mem.: 388.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
15-Feb-2024 08:48:54 2024-02-15 08:48:51        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:54 2024-02-15 08:48:52        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:54 2024-02-15 08:48:52        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:48:54 2024-02-15 08:48:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
15-Feb-2024 08:48:54 2024-02-15 08:48:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 5032 |   NRows selected : 5032
15-Feb-2024 08:48:54 2024-02-15 08:48:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
15-Feb-2024 08:48:54 2024-02-15 08:48:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 10065 && ROWNUMBER() <= 15093 |   NRows selected : 5029
15-Feb-2024 08:48:54 2024-02-15 08:48:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_point_wterm_vlad.ms | [Opened in readonly mode]
15-Feb-2024 08:48:54 2024-02-15 08:48:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 5033 && ROWNUMBER() <= 10064 |   NRows selected : 5032
15-Feb-2024 08:48:54 2024-02-15 08:48:53        INFO        tclean::::casa        ...done
15-Feb-2024 08:48:54 2024-02-15 08:48:54        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] :
15-Feb-2024 08:48:54 2024-02-15 08:48:54        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars : start
15-Feb-2024 08:48:54 2024-02-15 08:48:54        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]
15-Feb-2024 08:48:54 2024-02-15 08:48:54        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
15-Feb-2024 08:48:54 2024-02-15 08:48:54        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 1.397 GB. Available mem.: 388.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
15-Feb-2024 08:48:54 2024-02-15 08:48:54        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] :
15-Feb-2024 08:48:54 2024-02-15 08:48:54        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars : start
15-Feb-2024 08:49:08 2024-02-15 08:48:54        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]
15-Feb-2024 08:49:08 2024-02-15 08:48:54        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
15-Feb-2024 08:49:08 2024-02-15 08:48:54        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 1.397 GB. Available mem.: 388.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
15-Feb-2024 08:49:08 2024-02-15 08:48:54        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:49:08 2024-02-15 08:48:55        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] :
15-Feb-2024 08:49:08 2024-02-15 08:48:55        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars : start
15-Feb-2024 08:49:08 2024-02-15 08:48:55        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]
15-Feb-2024 08:49:08 2024-02-15 08:48:55        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
15-Feb-2024 08:49:08 2024-02-15 08:48:55        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 1.397 GB. Available mem.: 388.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
15-Feb-2024 08:49:08 2024-02-15 08:48:55        INFO        tclean::::casa        ...done
15-Feb-2024 08:49:08 2024-02-15 08:48:58        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:49:08 2024-02-15 08:48:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
15-Feb-2024 08:49:08 2024-02-15 08:48:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
15-Feb-2024 08:49:08 2024-02-15 08:48:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Uniform weighting: sidelobes will be suppressed over full image
15-Feb-2024 08:49:08 2024-02-15 08:48:59        INFO        tclean::::casa        ...done
15-Feb-2024 08:49:08 2024-02-15 08:48:59        INFO        tclean::::casa        Gathering/Merging/Scattering Weight Density for PSF generation
15-Feb-2024 08:49:08 2024-02-15 08:48:59        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:49:08 2024-02-15 08:49:00        INFO        tclean::::casa        ...done
15-Feb-2024 08:49:08 2024-02-15 08:49:00        INFO        tclean::::casa        ******************************************************
15-Feb-2024 08:49:08 2024-02-15 08:49:00        INFO        tclean::::casa         gather and scatter now
15-Feb-2024 08:49:08 2024-02-15 08:49:00        INFO        tclean::::casa        ******************************************************
15-Feb-2024 08:49:08 2024-02-15 08:49:00        INFO        SynthesisNormalizer::gatherImages         Gather  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
15-Feb-2024 08:49:08 2024-02-15 08:49:00        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]
15-Feb-2024 08:49:08 2024-02-15 08:49:00        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:49:08 2024-02-15 08:49:01        INFO        tclean::::casa        ...done
15-Feb-2024 08:49:08 2024-02-15 08:49:01        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.wyes] : hogbom
15-Feb-2024 08:49:08 2024-02-15 08:49:01        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        tclean::::casa        ...done
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        W projection using 16 planes
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Using maximum possible W = 5156.62 (wavelengths)
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Typical wavelength = 0.299798 (m)
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        W projection using 16 planes
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Using maximum possible W = 5156.62 (wavelengths)
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Typical wavelength = 0.299798 (m)
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        W projection using 16 planes
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Using maximum possible W = 5156.62 (wavelengths)
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Typical wavelength = 0.299798 (m)
15-Feb-2024 08:49:08 2024-02-15 08:49:02        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Scaling in W (at maximum W) = 22.9183 wavelengths per pixel
15-Feb-2024 08:49:08 2024-02-15 08:49:06        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
15-Feb-2024 08:49:08 2024-02-15 08:49:06        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-2        Memory used in gridding function = 2 MB from maximum 515213 MB
15-Feb-2024 08:49:08 2024-02-15 08:49:06        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
15-Feb-2024 08:49:08 2024-02-15 08:49:06        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-1        Memory used in gridding function = 2 MB from maximum 515213 MB
15-Feb-2024 08:49:08 2024-02-15 08:49:06        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
15-Feb-2024 08:49:08 2024-02-15 08:49:06        INFO        MPICommandServer::command_request_handler_service::WPConvFunc::findConvFunction::MPIServer-3        Memory used in gridding function = 2 MB from maximum 515213 MB
15-Feb-2024 08:49:08 2024-02-15 08:49:08        INFO        tclean::::casa        ...done
15-Feb-2024 08:49:37 2024-02-15 08:49:08        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
15-Feb-2024 08:49:37 2024-02-15 08:49:08        INFO        SIImageStore::calcSensitivity         [tst.wyes] Theoretical sensitivity (Jy/bm):0.00632763
15-Feb-2024 08:49:37 2024-02-15 08:49:08        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.042265
15-Feb-2024 08:49:37 2024-02-15 08:49:08        INFO        SIImageStore::printBeamSet         Beam : 85.3971 arcsec, 71.2504 arcsec, -89.0441 deg
15-Feb-2024 08:49:37 2024-02-15 08:49:08        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:49:37 2024-02-15 08:49:09        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::makePrimaryBeam::MPIServer-1         vi2 : Evaluating Primary Beam model onto image grid(s)
15-Feb-2024 08:49:37 2024-02-15 08:49:09        INFO        tclean::::casa        ...done
15-Feb-2024 08:49:37 2024-02-15 08:49:10        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]
15-Feb-2024 08:49:37 2024-02-15 08:49:10        INFO        SIImageStore::setModelImageOne         Starting model image tst.wyes.model does not exist. No initial prediction will be done
15-Feb-2024 08:49:37 2024-02-15 08:49:10        INFO        SIImageStore::setModelImageOne         Starting model image tst.wyes.model does not exist. No initial prediction will be done
15-Feb-2024 08:49:37 2024-02-15 08:49:10        INFO        SIImageStore::setModelImageOne         Starting model image tst.wyes.model does not exist. No initial prediction will be done
15-Feb-2024 08:49:37 2024-02-15 08:49:10        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
15-Feb-2024 08:49:37 2024-02-15 08:49:10        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:49:37 2024-02-15 08:49:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
15-Feb-2024 08:49:37 2024-02-15 08:49:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
15-Feb-2024 08:49:37 2024-02-15 08:49:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
15-Feb-2024 08:49:37 2024-02-15 08:49:12        INFO        tclean::::casa        ...done
15-Feb-2024 08:49:37 2024-02-15 08:49:12        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
15-Feb-2024 08:49:37 2024-02-15 08:49:12        INFO        task_tclean::SDAlgorithmBase::restore         [tst.wyes] : Restoring model image.
15-Feb-2024 08:49:37 2024-02-15 08:49:12        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
15-Feb-2024 08:49:37 2024-02-15 08:49:12        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 85.3971 arcsec, 71.2504 arcsec, -89.0441 deg
15-Feb-2024 08:49:37 2024-02-15 08:49:20        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
15-Feb-2024 08:49:37 2024-02-15 08:49:20        INFO        ::casa::MPIServer-1        node 1 has completed its job
15-Feb-2024 08:49:37 2024-02-15 08:49:20        INFO        ::casa::MPIServer-3        node 3 has completed its job
15-Feb-2024 08:49:37 2024-02-15 08:49:20        INFO        ::casa::MPIServer-2        node 2 has completed its job
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa        ...done
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa        Searching for images with prefix 'tst.wyes'... Found these, writing history into them: ['tst.wyes.psf', 'tst.wyes.pb', 'tst.wyes.gridwt', 'tst.wyes.image', 'tst.wyes.residual', 'tst.wyes.sumwt', 'tst.wyes.model']
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa        Task tclean complete. Start time: 2024-02-15 08:48:48.031173 End time: 2024-02-15 08:49:21.261859
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa        ##### End Task: tclean               #####
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa        ##########################################
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        name::        No table opened.
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        showcache::        The Table Cache is empty.
15-Feb-2024 08:49:37 [check_tfmask]  T/F mask name for tst.wyes.image is : ['T'] (Pass : should be ['T'])
15-Feb-2024 08:49:37
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa        [ checkall ]
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa+        [ check_ims ] Image made : ['tst.wyes.image'] =  [True] ( Pass : should all be True )
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa+        [check_history] All expected history entries found. (Pass)
15-Feb-2024 08:49:37 2024-02-15 08:49:21        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)
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa+        [ check_pix_val ] tst.wyes.image : Value is 0.9963294267654419 at [1158, 1384, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
15-Feb-2024 08:49:37 2024-02-15 08:49:21        INFO        tclean::::casa+        [check_tfmask]  T/F mask name for tst.wyes.image is : ['T'] (Pass : should be ['T'])
15-Feb-2024 08:49:37 .s
15-Feb-2024 08:49:37 ----------------------------------------------------------------------
15-Feb-2024 08:49:37 Ran 294 tests in 11503.657s
15-Feb-2024 08:49:37
15-Feb-2024 08:49:37 OK (skipped=28)
15-Feb-2024 08:49:37 Marking test as passed.
15-Feb-2024 08:49:37 2024-02-15 08:49:29        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 1
15-Feb-2024 08:49:37 2024-02-15 08:49:29        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 2
15-Feb-2024 08:49:37 2024-02-15 08:49:29        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 3
15-Feb-2024 08:49:37 2024-02-15 08:49:32        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal self.signal_stop_service_request() successfully handled by server 1
15-Feb-2024 08:49:37 2024-02-15 08:49:32        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal self.signal_stop_service_request() successfully handled by server 2
15-Feb-2024 08:49:37 2024-02-15 08:49:32        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal self.signal_stop_service_request() successfully handled by server 3
15-Feb-2024 08:49:37 2024-02-15 08:49:35        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        stop service signal received, stopping services...
15-Feb-2024 08:49:37 2024-02-15 08:49:35        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        stop service signal received, stopping services...
15-Feb-2024 08:49:37 2024-02-15 08:49:35        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        stop service signal received, stopping services...
15-Feb-2024 08:49:37 2024-02-15 08:49:37        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-2        MPI command request handler service stopped
15-Feb-2024 08:49:37 2024-02-15 08:49:37        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Going to finalize MPI environment
15-Feb-2024 08:49:37 2024-02-15 08:49:37        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-1        MPI command request handler service stopped
15-Feb-2024 08:49:37 2024-02-15 08:49:37        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Going to finalize MPI environment
15-Feb-2024 08:49:37 2024-02-15 08:49:38        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-3        MPI command request handler service stopped
15-Feb-2024 08:49:37 2024-02-15 08:49:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Going to finalize MPI environment
15-Feb-2024 08:49:37 2024-02-15 08:49:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Exiting
15-Feb-2024 08:49:37 2024-02-15 08:49:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Exiting
15-Feb-2024 08:49:37 2024-02-15 08:49:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Exiting
15-Feb-2024 08:49:37 Executor result: 0
15-Feb-2024 08:49:37 Failed tests:[]
15-Feb-2024 08:49:37
15-Feb-2024 08:49:37 Results: [{'testname': 'test_task_tclean', 'runtime': '3:12:02.876022', 'returncode': 0, 'testerr': []}]
15-Feb-2024 08:49:37 Stopping Xvfb.
15-Feb-2024 08:49:37 Stopping framebuffer <Popen: returncode: None args: ['Xvfb', ':128', '-screen', '0', '2048x2048x2...>
15-Feb-2024 08:49:37 Virtual frame buffer deployed at :128 with pid 139 successfully shutdown
15-Feb-2024 08:49:37 Xvfb stopped.
15-Feb-2024 08:49:38 total 1258568
15-Feb-2024 08:49:38 -rw-r--r-- 1 casaci    15001 1288769504 Feb 15 05:35 casa-CAS-14273-3-py3.8.el8.tar.xz
15-Feb-2024 08:49:38 -rw-r--r-- 1 casaci casatest        217 Feb 15 08:49 xUnit-suite-mpi-8.xml
15-Feb-2024 08:49:38 Finished task 'MPI Tasks Tests on EL8' with result: Success
15-Feb-2024 08:49:38 Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
15-Feb-2024 08:49:38 Parsing test results under /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMPI5T1-MTTR8D...
15-Feb-2024 08:49:38 Finished task 'Parse test results' with result: Success
15-Feb-2024 08:49:38
Substituting variable: ${bamboo.capability.hostname} with cvpost134
15-Feb-2024 08:49:38
Substituting variable: ${bamboo.capability.hostname} with cvpost134
15-Feb-2024 08:49:38 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
15-Feb-2024 08:49:38
Beginning to execute external process for build 'CASA - CMake MPI5 Test - CAS-14273 - MPI Task Tclean Rocky 8.5 Docker #4 (CASA-CMPI5T1-MTTR8D-4)'
... running command line:
/export/cvpost134-nvme/casa/bamboohome/temp/CASA-CMPI5T1-MTTR8D-4-ScriptBuildTask-5542280779347985035.sh
... in: /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMPI5T1-MTTR8D
15-Feb-2024 08:49:38 Starting cleanup
15-Feb-2024 08:49:48 test-container
15-Feb-2024 08:49:54 test-container
15-Feb-2024 08:49:54 Error response from daemon: No such container: test-container-cvpost134
15-Feb-2024 08:49:54 Error response from daemon: No such container: test-container-cvpost134
15-Feb-2024 08:49:54 Cleanup complete
15-Feb-2024 08:49:54 Finished task 'Cleanup' with result: Success
15-Feb-2024 08:49:54 Running post build plugin 'Docker Container Cleanup'
15-Feb-2024 08:49:54 Running post build plugin 'NCover Results Collector'
15-Feb-2024 08:49:54 Running post build plugin 'Build Results Label Collector'
15-Feb-2024 08:49:54 Running post build plugin 'Clover Results Collector'
15-Feb-2024 08:49:54 Running post build plugin 'npm Cache Cleanup'
15-Feb-2024 08:49:54 Running post build plugin 'Artifact Copier'
15-Feb-2024 08:49:54 Finalising the build...
15-Feb-2024 08:49:54 Stopping timer.
15-Feb-2024 08:49:54 Build CASA-CMPI5T1-MTTR8D-4 completed.
15-Feb-2024 03:49:54 Running on server: post build plugin 'Build Hanging Detection Configuration'
15-Feb-2024 03:49:54 Running on server: post build plugin 'NCover Results Collector'
15-Feb-2024 03:49:54 Running on server: post build plugin 'Build Labeller'
15-Feb-2024 03:49:54 Running on server: post build plugin 'Clover Delta Calculator'
15-Feb-2024 03:49:54 Running on server: post build plugin 'Maven Dependencies Postprocessor'
15-Feb-2024 03:49:54 All post build plugins have finished
15-Feb-2024 03:49:54 Generating build results summary...
15-Feb-2024 03:49:54 Saving build results to disk...
15-Feb-2024 03:49:54 Store variable context...
15-Feb-2024 03:49:54 Finished building CASA-CMPI5T1-MTTR8D-4.