Build: #42 failed

Job: VLA Stakeholder Tests MPI EL8 Docker was successful

Build log

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

03-May-2024 13:27:58 2024-05-03 13:27:11        INFO        SynthesisImagerVi2::selectData         MS : 3c391_ctm_mosaic_spw0.ms | [Opened in readonly mode]
03-May-2024 13:27:58 2024-05-03 13:27:11        INFO        SynthesisImagerVi2::selectData           NRows selected : 767459
03-May-2024 13:27:58 2024-05-03 13:27:12        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [3c391_ctm_spw0_multiscale] :
03-May-2024 13:27:58 2024-05-03 13:27:13        INFO        SynthesisImagerVi2::defineImage         Impars : start
03-May-2024 13:27:58 2024-05-03 13:27:13        INFO        SynthesisImagerVi2::defineImage         Shape : [480, 480, 1, 1]Spectral : [4.59835e+09] at [0] with increment [1.27992e+08]
03-May-2024 13:27:58 2024-05-03 13:27:13        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [3c391_ctm_spw0_multiscale] with ftmachine : mosaicft
03-May-2024 13:27:58 2024-05-03 13:27:13        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
03-May-2024 13:27:58 2024-05-03 13:27:13        INFO        VPSkyJones::VPSkyJones        PB used EVLA
03-May-2024 13:27:58 2024-05-03 13:27:13        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.1978 GB. Available mem.: 388 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 8.
03-May-2024 13:27:58 2024-05-03 13:27:13        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Briggs weighting: sidelobes will be suppressed over full image
03-May-2024 13:27:58 2024-05-03 13:27:21        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:21        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:21        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:21        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:21        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:21        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:21        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:21        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:27:58 2024-05-03 13:27:22        INFO        tclean::::casa        ...done
03-May-2024 13:27:58 2024-05-03 13:27:22        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:27:58 2024-05-03 13:27:23        INFO        tclean::::casa        ...done
03-May-2024 13:27:58 2024-05-03 13:27:26        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:27:58 2024-05-03 13:27:27        INFO        tclean::::casa        ...done
03-May-2024 13:27:58 2024-05-03 13:27:32        INFO        SynthesisImagerVi2::selectData         MS : 3c391_ctm_mosaic_spw0.ms | [Opened in readonly mode]
03-May-2024 13:27:58 2024-05-03 13:27:32        INFO        SynthesisImagerVi2::selectData           NRows selected : 767459
03-May-2024 13:27:58 2024-05-03 13:27:33        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [3c391_ctm_spw0_multiscale] :
03-May-2024 13:27:58 2024-05-03 13:27:34        INFO        SynthesisImagerVi2::defineImage         Impars : start
03-May-2024 13:27:58 2024-05-03 13:27:34        INFO        SynthesisImagerVi2::defineImage         Shape : [480, 480, 1, 1]Spectral : [4.59835e+09] at [0] with increment [1.27992e+08]
03-May-2024 13:27:58 2024-05-03 13:27:34        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [3c391_ctm_spw0_multiscale] with ftmachine : mosaicft
03-May-2024 13:27:58 2024-05-03 13:27:34        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
03-May-2024 13:27:58 2024-05-03 13:27:34        INFO        VPSkyJones::VPSkyJones        PB used EVLA
03-May-2024 13:27:58 2024-05-03 13:27:34        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.1978 GB. Available mem.: 388 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 8.
03-May-2024 13:27:58 2024-05-03 13:27:34        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Briggs weighting: sidelobes will be suppressed over full image
03-May-2024 13:27:58 2024-05-03 13:27:42        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:42        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:42        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:42        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:42        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:42        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:42        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
03-May-2024 13:27:58 2024-05-03 13:27:42        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:27:58 2024-05-03 13:27:43        INFO        tclean::::casa        ...done
03-May-2024 13:27:58 2024-05-03 13:27:43        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [3c391_ctm_spw0_multiscale] : multiscale
03-May-2024 13:27:58 2024-05-03 13:27:43        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:27:58 2024-05-03 13:27:44        INFO        tclean::::casa        ...done
03-May-2024 13:27:58 2024-05-03 13:27:44        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:27:58
03-May-2024 13:27:58 0%....10....20....30....40....50.
03-May-2024 13:27:58 0%....10....20....30....40....50.
03-May-2024 13:27:58 0%....10....20....30....40.
03-May-2024 13:27:58 0%....10....20....30....
03-May-2024 13:27:58 0%....10....20....30....40
03-May-2024 13:27:58 0%....10....20....30....40....50..40.................6060.506050....
03-May-2024 13:27:58 0%....10....20....30....40....50....60.........50............70.60.707070.60..................60....80...8070.8080....70.............70.........908090...9090...........100%
03-May-2024 13:27:58 .80...100%
03-May-2024 13:27:58 ...100%
03-May-2024 13:27:58 .80...100%
03-May-2024 13:27:58 .90.........100%
03-May-2024 13:27:58 .90..90....100%
03-May-2024 13:27:58 ...100%
03-May-2024 13:27:58 2024-05-03 13:27:58        INFO        tclean::::casa        ...done
03-May-2024 13:28:10 2024-05-03 13:27:58        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:28:10 2024-05-03 13:27:59        INFO        SIImageStore::calcSensitivity         [3c391_ctm_spw0_multiscale] Theoretical sensitivity (Jy/bm):1.06835e-05
03-May-2024 13:28:10 2024-05-03 13:27:59        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.005895
03-May-2024 13:28:10 2024-05-03 13:27:59        INFO        SIImageStore::printBeamSet         Beam : 17.0639 arcsec, 14.9556 arcsec, 21.6315 deg
03-May-2024 13:28:10 2024-05-03 13:27:59        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        tclean::::casa        ...done
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:28:10 2024-05-03 13:28:00        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:28:10
03-May-2024 13:28:10 0%....10....20....30....40....50....60
03-May-2024 13:28:10 0%....10....20....30....40....50..
03-May-2024 13:28:10 0%....10....20....30....40....50....60....70...
03-May-2024 13:28:10 0%....10....20....30....40....50....60....70..
03-May-2024 13:28:10 0%....10....20....30....40....50....60....70..
03-May-2024 13:28:10 0%....10....20....30....40....50....60
03-May-2024 13:28:10 0%....10....20....30....40....50....60....70..........80....80...8080......60.70......70.........90...90.90...9070....100%
03-May-2024 13:28:10 .80...100%
03-May-2024 13:28:10 .......100%
03-May-2024 13:28:10 .....100%
03-May-2024 13:28:10 ..80....9080.......100%
03-May-2024 13:28:10 ..90......100%
03-May-2024 13:28:10 90....100%
03-May-2024 13:28:10 2024-05-03 13:28:10        INFO        tclean::::casa        ...done
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.123932, over full image : 0.123932
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        SynthesisDeconvolver::setupMask         [3c391_ctm_spw0_multiscale] Setting up a mask from [3c391_clean_mask.crtf]
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO                You've specified explicitly that the spectral axis should be regridded. However, the input image has a degenerate spectral axis and so it cannot be regridded. Instead, the resulting single output channel will be replicated 1 times in the output image.
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        RegionTextParser::_determineVersion        RegionTextParser::_determineVersion: Found spec version 0
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        SynthesisDeconvolver::setupMask         ----------------------------------------------------------------------------------------------------------------------------------------
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        SynthesisDeconvolver::setupMask         [3c391_ctm_spw0_multiscale] Number of pixels in the clean mask : 19718 out of a total of 230400 pixels. [ 8.55816 % ]
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.123932, over full image : 0.123932
03-May-2024 13:28:10 2024-05-03 13:28:11        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 20000, 'cyclethreshold': 0.011826764792203903, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.0118268, CycleNiter=20000, Gain=0.1
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.469931 within the mask
03-May-2024 13:28:33 2024-05-03 13:28:11        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:28:33 2024-05-03 13:28:13        INFO        MatrixCleaner::clean()         200      0.0712141      2.46328
03-May-2024 13:28:33 2024-05-03 13:28:14        INFO        MatrixCleaner::clean()         400      0.0422464      3.36933
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        MatrixCleaner::clean()         Reached stopping threshold 0.0118268 at iteration 502
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        MatrixCleaner::clean()         Optimum flux is 0.0116264
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        MatrixCleaner::clean()           0    0.0627742
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        MatrixCleaner::clean()           1    0.172396
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        MatrixCleaner::clean()           2    3.43351
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        MatrixCleaner::clean()           3    0.0570872
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->503 [503], model=0->3.7246, peakres=0.123932->0.0116264, Reached cyclethreshold.
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 503 iterations.
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:28:33 2024-05-03 13:28:15        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:28:33 2024-05-03 13:28:16        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:28:33 2024-05-03 13:28:16        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:28:33 2024-05-03 13:28:16        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:28:33 2024-05-03 13:28:16        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:28:33 2024-05-03 13:28:16        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:28:33 2024-05-03 13:28:16        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:28:33
03-May-2024 13:28:33 0%....10....20....30...
03-May-2024 13:28:33 0%....10....20....30...
03-May-2024 13:28:33 0%....10....
03-May-2024 13:28:33 0%....10....20....30..
03-May-2024 13:28:33 0%....10....20
03-May-2024 13:28:33 0%....10....20....30...
03-May-2024 13:28:33 0%....10....20.20...40..40.40..40..............30........3050.5050.....50......30........40....40..60....60....60....6040...........50.50.70.........70..70.70.........50...6060......80....80..80...80.............70..60....70.90....9090...90...100%
03-May-2024 13:28:33 ........100%
03-May-2024 13:28:33 ..80...100%
03-May-2024 13:28:33 70...100%
03-May-2024 13:28:33 .......80....90..80...100%
03-May-2024 13:28:33 ...90......100%
03-May-2024 13:28:33 .90....100%
03-May-2024 13:28:33 2024-05-03 13:28:34        INFO        tclean::::casa        ...done
03-May-2024 13:28:33 2024-05-03 13:28:34        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:28:33 2024-05-03 13:28:34        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0142191, over full image : 0.0142191
03-May-2024 13:28:33 2024-05-03 13:28:34        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 19497, 'cyclethreshold': 0.0013569192960858345, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
03-May-2024 13:28:33 2024-05-03 13:28:34        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.00135692, CycleNiter=19497, Gain=0.1
03-May-2024 13:28:33 2024-05-03 13:28:34        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:28:33 2024-05-03 13:28:34        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:28:33 2024-05-03 13:28:34        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:28:33 2024-05-03 13:28:34        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0505523 within the mask
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()         Diverging due to large scale?
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()           0    0
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()           1    0.0069819
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()           2    0.175678
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        MatrixCleaner::clean()           3    0.0273352
03-May-2024 13:28:52 2024-05-03 13:28:34        WARN        SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185)        MSClean minor cycle stopped at large scale negative or diverging
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->49 [49], model=3.72461->3.90727, peakres=0.0142191->0.0117853, Exited multiscale minor cycle without reaching any stopping criterion.
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 552 iterations.
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:28:52 2024-05-03 13:28:34        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:28:52 2024-05-03 13:28:35        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:28:52 2024-05-03 13:28:35        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:28:52 2024-05-03 13:28:35        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:28:52 2024-05-03 13:28:35        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:28:52 2024-05-03 13:28:35        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:28:52 2024-05-03 13:28:35        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:28:52
03-May-2024 13:28:52 0%....10....20....30...
03-May-2024 13:28:52 0%....10....20....30...
03-May-2024 13:28:52 0%....10....
03-May-2024 13:28:52 0%....10....20
03-May-2024 13:28:52 0%....10....20....30...
03-May-2024 13:28:52 0%....10....20
03-May-2024 13:28:52 0%....10....20....30...20...4040.....40.40..............30.......305050..50.....50...30..........40....40..60.....60.60.....40.60...........50.50..70.....70......70.70.....50.......6060....80......80...80.80............60..70.....70.90....90...90.90...100%
03-May-2024 13:28:52 .......100%
03-May-2024 13:28:52 ..70.80..100%
03-May-2024 13:28:52 ...100%
03-May-2024 13:28:52 ........80...90.80....100%
03-May-2024 13:28:52 ....90.....100%
03-May-2024 13:28:52 .90....100%
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        tclean::::casa        ...done
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0119035, over full image : 0.0119035
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 19448, 'cyclethreshold': 0.001135940896347165, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.00113594, CycleNiter=19448, Gain=0.1
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:28:52 2024-05-03 13:28:53        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:29:28 2024-05-03 13:28:53        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:29:28 2024-05-03 13:28:53        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:29:28 2024-05-03 13:28:53        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:29:28 2024-05-03 13:28:53        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:29:28 2024-05-03 13:28:53        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:29:28 2024-05-03 13:28:53        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:29:28 2024-05-03 13:28:53        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.277388 within the mask
03-May-2024 13:29:28 2024-05-03 13:28:53        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:29:28 2024-05-03 13:28:55        INFO        MatrixCleaner::clean()         200      0.0256308      0.678161
03-May-2024 13:29:28 2024-05-03 13:28:56        INFO        MatrixCleaner::clean()         400      0.00678202      1.10411
03-May-2024 13:29:28 2024-05-03 13:28:58        INFO        MatrixCleaner::clean()         600      0.00736689      1.41764
03-May-2024 13:29:28 2024-05-03 13:29:00        INFO        MatrixCleaner::clean()         800      0.0139059      1.6712
03-May-2024 13:29:28 2024-05-03 13:29:02        INFO        MatrixCleaner::clean()         1000      0.00406682      1.8746
03-May-2024 13:29:28 2024-05-03 13:29:03        INFO        MatrixCleaner::clean()         1200      0.0103415      2.04536
03-May-2024 13:29:28 2024-05-03 13:29:05        INFO        MatrixCleaner::clean()         1400      0.0031618      2.19537
03-May-2024 13:29:28 2024-05-03 13:29:07        INFO        MatrixCleaner::clean()         1600      0.00279609      2.32169
03-May-2024 13:29:28 2024-05-03 13:29:09        INFO        MatrixCleaner::clean()         1800      0.00335221      2.4371
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         2000      0.00671456      2.53882
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()           0    0.134221
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()           1    0.165437
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()           2    1.95941
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()           3    0.279746
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         Failed to reach stopping threshold
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00301749 within the mask
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()         Diverging due to large scale?
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()           0    0
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()           1    0.000301749
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()           2    0.000671242
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        MatrixCleaner::clean()           3    0
03-May-2024 13:29:28 2024-05-03 13:29:10        WARN        SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185)        MSClean minor cycle stopped at large scale negative or diverging
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->2002 [2002], model=3.90727->6.44637, peakres=0.0119035->0.011904, Possible divergence. Peak residual increased by 10% from minimum.
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 2554 iterations.
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:29:28 2024-05-03 13:29:10        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:29:28 2024-05-03 13:29:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:29:28 2024-05-03 13:29:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:29:28 2024-05-03 13:29:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:29:28 2024-05-03 13:29:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:29:28 2024-05-03 13:29:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:29:28 2024-05-03 13:29:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:29:28 2024-05-03 13:29:11        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:29:28 2024-05-03 13:29:11        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:29:28
03-May-2024 13:29:28 0%....10....20....30..
03-May-2024 13:29:28 0%....10....20....30.
03-May-2024 13:29:28 0%....10....20....30...
03-May-2024 13:29:28 0%....10....
03-May-2024 13:29:28 0%....10....20
03-May-2024 13:29:28 0%....10....
03-May-2024 13:29:28 0%....10....20....30....20..20...40.40..40..40..............30........30.5050.50...50......30.........40....40....60..60...60.....6040...........5050...70.......70....7070........50....6060.......80...80..80...80............70..60.....70..90...9090..90.....100%
03-May-2024 13:29:28 .......100%
03-May-2024 13:29:28 ...80..100%
03-May-2024 13:29:28 70...100%
03-May-2024 13:29:28 .......80....90..80...100%
03-May-2024 13:29:28 ...90......100%
03-May-2024 13:29:28 .90....100%
03-May-2024 13:29:28 2024-05-03 13:29:29        INFO        tclean::::casa        ...done
03-May-2024 13:29:28 2024-05-03 13:29:29        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00333072, over full image : 0.00575185
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 17446, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=17446, Gain=0.1
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0129891 within the mask
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()         Diverging due to large scale?
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()           0    0
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()           1    0
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()           2    0.0170775
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        MatrixCleaner::clean()           3    0.00871298
03-May-2024 13:29:47 2024-05-03 13:29:29        WARN        SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185)        MSClean minor cycle stopped at large scale negative or diverging
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->15 [15], model=6.4464->6.46345, peakres=0.00333072->0.00316979, Exited multiscale minor cycle without reaching any stopping criterion.
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 2569 iterations.
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:29:47 2024-05-03 13:29:29        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:29:47
03-May-2024 13:29:47 0%....10....20
03-May-2024 13:29:47 0%....10....20
03-May-2024 13:29:47 0%....10....20....30...
03-May-2024 13:29:47 0%....10....20....30....
03-May-2024 13:29:47 0%....10....20
03-May-2024 13:29:47 0%....10....20....30...
03-May-2024 13:29:47 0%....10....20....30.....40.40...40..40..............30......30.505050........50..30..........40....40..60....60...60.....4060..........50...50.70.......70....7070.......50.....60.60....80......80.80...80.............60..70...70...90...90.90...90...100%
03-May-2024 13:29:47 ........100%
03-May-2024 13:29:47 .70.80..100%
03-May-2024 13:29:47 ...100%
03-May-2024 13:29:47 ........80...90..80...100%
03-May-2024 13:29:47 ...90......100%
03-May-2024 13:29:47 .90....100%
03-May-2024 13:29:47 2024-05-03 13:29:47        INFO        tclean::::casa        ...done
03-May-2024 13:29:47 2024-05-03 13:29:47        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:29:47 2024-05-03 13:29:48        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00317446, over full image : 0.00575647
03-May-2024 13:29:47 2024-05-03 13:29:48        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 17431, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
03-May-2024 13:29:47 2024-05-03 13:29:48        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=17431, Gain=0.1
03-May-2024 13:29:47 2024-05-03 13:29:48        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0875262 within the mask
03-May-2024 13:30:24 2024-05-03 13:29:48        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:30:24 2024-05-03 13:29:49        INFO        MatrixCleaner::clean()         200      0.00808871      0.259702
03-May-2024 13:30:24 2024-05-03 13:29:51        INFO        MatrixCleaner::clean()         400      0.00309816      0.42819
03-May-2024 13:30:24 2024-05-03 13:29:53        INFO        MatrixCleaner::clean()         600      0.00615639      0.557607
03-May-2024 13:30:24 2024-05-03 13:29:55        INFO        MatrixCleaner::clean()         800      0.00559943      0.652203
03-May-2024 13:30:24 2024-05-03 13:29:56        INFO        MatrixCleaner::clean()         1000      0.00516054      0.738602
03-May-2024 13:30:24 2024-05-03 13:29:58        INFO        MatrixCleaner::clean()         1200      0.00479932      0.812358
03-May-2024 13:30:24 2024-05-03 13:30:00        INFO        MatrixCleaner::clean()         1400      0.0015108      0.882195
03-May-2024 13:30:24 2024-05-03 13:30:02        INFO        MatrixCleaner::clean()         1600      0.00140846      0.940693
03-May-2024 13:30:24 2024-05-03 13:30:03        INFO        MatrixCleaner::clean()         1800      0.0038991      0.993082
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         2000      0.00365962      1.0451
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()           0    0.0917588
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()           1    0.0536219
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()           2    0.673181
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()           3    0.226539
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         Failed to reach stopping threshold
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0036602 within the mask
03-May-2024 13:30:24 2024-05-03 13:30:05        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        MatrixCleaner::clean()         Diverging due to large scale?
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        MatrixCleaner::clean()           0    0.00381694
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        MatrixCleaner::clean()           1    0.00228871
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        MatrixCleaner::clean()           2    0.00797827
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        MatrixCleaner::clean()           3    0.00271733
03-May-2024 13:30:24 2024-05-03 13:30:06        WARN        SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185)        MSClean minor cycle stopped at large scale negative or diverging
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->2074 [2074], model=6.46347->7.52264, peakres=0.00317446->0.00315608, Possible divergence. Peak residual increased by 10% from minimum.
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 4643 iterations.
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:30:24 2024-05-03 13:30:06        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:30:24
03-May-2024 13:30:24 0%....10....20....30....
03-May-2024 13:30:24 0%....10....20....30....40
03-May-2024 13:30:24 0%....10....20
03-May-2024 13:30:24 0%....10....20
03-May-2024 13:30:24 0%....10....20.
03-May-2024 13:30:24 0%....10....20....30....40.
03-May-2024 13:30:24 0%....10....20....30.....40..40.................30...30..50.5050...50.....30............40.40......60..60..60....40.60........50......50..70......70...7070.......50.....60..60......80..80....80.80.............60..70...70...90...90.9090......100%
03-May-2024 13:30:24 .......100%
03-May-2024 13:30:24 ...70..100%
03-May-2024 13:30:24 80....100%
03-May-2024 13:30:24 ......80....9080......100%
03-May-2024 13:30:24 ..90......100%
03-May-2024 13:30:24 .90....100%
03-May-2024 13:30:24 2024-05-03 13:30:24        INFO        tclean::::casa        ...done
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00164042, over full image : 0.00413188
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 15357, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=15357, Gain=0.1
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00613188 within the mask
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()         Diverging due to large scale?
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()           0    0
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()           1    0
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()           2    0.00120038
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        MatrixCleaner::clean()           3    0.00437447
03-May-2024 13:30:42 2024-05-03 13:30:24        WARN        SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185)        MSClean minor cycle stopped at large scale negative or diverging
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->3 [3], model=7.52266->7.52385, peakres=0.00164042->0.00163631, Exited multiscale minor cycle without reaching any stopping criterion.
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 4646 iterations.
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:30:42 2024-05-03 13:30:24        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:30:42 2024-05-03 13:30:25        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:30:42 2024-05-03 13:30:25        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:30:42 2024-05-03 13:30:25        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:30:42 2024-05-03 13:30:25        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:30:42 2024-05-03 13:30:25        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:30:42 2024-05-03 13:30:25        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:30:42 2024-05-03 13:30:25        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:30:42
03-May-2024 13:30:42 0%....10....20....30...
03-May-2024 13:30:42 0%....10....
03-May-2024 13:30:42 0%....10....20
03-May-2024 13:30:42 0%....10....20
03-May-2024 13:30:42 0%....10....20....30.
03-May-2024 13:30:42 0%....10....20....30...
03-May-2024 13:30:42 0%....10....20....30.....20...4040.....40.40..............30.......305050.50......50...30..........40....40.60.....60..60.....40.60...........50.50.70........70....7070.......50......6060....80......80.80...80............60..70.....7090.....90..90.90....100%
03-May-2024 13:30:42 .......100%
03-May-2024 13:30:42 ...7080..100%
03-May-2024 13:30:42 ...100%
03-May-2024 13:30:42 ........80...90.80....100%
03-May-2024 13:30:42 ...90......100%
03-May-2024 13:30:42 .90....100%
03-May-2024 13:30:42 2024-05-03 13:30:43        INFO        tclean::::casa        ...done
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0016366, over full image : 0.00414207
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 15354, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=15354, Gain=0.1
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0437821 within the mask
03-May-2024 13:31:10 2024-05-03 13:30:43        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:31:10 2024-05-03 13:30:45        INFO        MatrixCleaner::clean()         200      0.00423086      0.140161
03-May-2024 13:31:10 2024-05-03 13:30:46        INFO        MatrixCleaner::clean()         400      0.00375539      0.23176
03-May-2024 13:31:10 2024-05-03 13:30:48        INFO        MatrixCleaner::clean()         600      0.00345898      0.295383
03-May-2024 13:31:10 2024-05-03 13:30:50        INFO        MatrixCleaner::clean()         800      0.00110185      0.3498
03-May-2024 13:31:10 2024-05-03 13:30:52        INFO        MatrixCleaner::clean()         1000      0.00103119      0.394064
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        MatrixCleaner::clean()         Reached stopping threshold 0.001 at iteration 1105
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        MatrixCleaner::clean()         Optimum flux is 0.000999994
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        MatrixCleaner::clean()           0    0.0404701
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        MatrixCleaner::clean()           1    0.0171541
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        MatrixCleaner::clean()           2    0.224657
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        MatrixCleaner::clean()           3    0.134935
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->1106 [1106], model=7.52386->7.94095, peakres=0.0016366->0.000999996, Reached cyclethreshold.
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 5752 iterations.
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:31:10 2024-05-03 13:30:53        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:31:10
03-May-2024 13:31:10 0%....10....20.
03-May-2024 13:31:10 0%....10....20.
03-May-2024 13:31:10 0%....10....20.
03-May-2024 13:31:10 0%....10....20....30....40
03-May-2024 13:31:10 0%....10....20....30....40.
03-May-2024 13:31:10 0%....10....20....30....40.
03-May-2024 13:31:10 0%....10....20....30....40................30....30..5050.50....50.....30...........40.40.....60..60....60...40.60...........50..50...70......70...7070.......50......60..60.....80..80..80...80............60...70...70...90...9090.90......100%
03-May-2024 13:31:10 ........100%
03-May-2024 13:31:10 ..70..100%
03-May-2024 13:31:10 80...100%
03-May-2024 13:31:10 ........80...9080......100%
03-May-2024 13:31:10 ..90......100%
03-May-2024 13:31:10 .90....100%
03-May-2024 13:31:10 2024-05-03 13:31:11        INFO        tclean::::casa        ...done
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00116847, over full image : 0.00357675
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 14248, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=14248, Gain=0.1
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00385218 within the mask
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()         Diverging due to large scale?
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()           0    0
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()           1    0
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()           2    0.000385218
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        MatrixCleaner::clean()           3    0.00282378
03-May-2024 13:31:29 2024-05-03 13:31:11        WARN        SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185)        MSClean minor cycle stopped at large scale negative or diverging
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->2 [2], model=7.94098->7.94133, peakres=0.00116847->0.00114002, Exited multiscale minor cycle without reaching any stopping criterion.
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 5754 iterations.
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:31:29 2024-05-03 13:31:11        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:31:29
03-May-2024 13:31:29 0%....10....20
03-May-2024 13:31:29 0%....10....20....30...
03-May-2024 13:31:29 0%....10....20
03-May-2024 13:31:29 0%....10....20
03-May-2024 13:31:29 0%....10....20....30...
03-May-2024 13:31:29 0%....10....20....30....
03-May-2024 13:31:29 0%....10....20....30.....40..40..40..40...............30.....30.5050.50.......50...30.........40...40..60.....60...60.....4060..........50...50.70.......70....7070.......50.....60.60......80....80..80..80.............60..70...70...90...90.90.90.....100%
03-May-2024 13:31:29 .........100%
03-May-2024 13:31:29 .7080..100%
03-May-2024 13:31:29 ...100%
03-May-2024 13:31:29 .......80....90..80...100%
03-May-2024 13:31:29 ...90......100%
03-May-2024 13:31:29 .90....100%
03-May-2024 13:31:29 2024-05-03 13:31:29        INFO        tclean::::casa        ...done
03-May-2024 13:31:50 2024-05-03 13:31:29        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0011405, over full image : 0.00358032
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 14246, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=14246, Gain=0.1
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0282502 within the mask
03-May-2024 13:31:50 2024-05-03 13:31:30        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:31:50 2024-05-03 13:31:31        INFO        MatrixCleaner::clean()         200      0.00305981      0.0944848
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        MatrixCleaner::clean()         Reached stopping threshold 0.001 at iteration 266
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        MatrixCleaner::clean()         Optimum flux is 0.000999575
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        MatrixCleaner::clean()           0    0.00630624
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        MatrixCleaner::clean()           1    0.00193761
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        MatrixCleaner::clean()           2    0.0560496
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        MatrixCleaner::clean()           3    0.0444036
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->267 [267], model=7.94136->8.04988, peakres=0.0011405->0.000999574, Reached cyclethreshold.
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 6021 iterations.
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:31:50 2024-05-03 13:31:32        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:31:50
03-May-2024 13:31:50 0%....10....20
03-May-2024 13:31:50 0%....10....20....30...
03-May-2024 13:31:50 0%....10....20....30....
03-May-2024 13:31:50 0%....10....20
03-May-2024 13:31:50 0%....10....20
03-May-2024 13:31:50 0%....10....20....30...
03-May-2024 13:31:50 0%....10....20....30.....40.40..40...40................30....30.5050.50.......50...30.........40....40..60....60..60......4060..........50...5070.......70.....7070.......50.....60.60....80......80.80...80..............60.70...70...90...9090..90.....100%
03-May-2024 13:31:50 .....100%
03-May-2024 13:31:50 .....7080..100%
03-May-2024 13:31:50 ...100%
03-May-2024 13:31:50 .......80....90..80...100%
03-May-2024 13:31:50 ...90......100%
03-May-2024 13:31:50 .90....100%
03-May-2024 13:31:50 2024-05-03 13:31:50        INFO        tclean::::casa        ...done
03-May-2024 13:32:08 2024-05-03 13:31:50        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:32:08 2024-05-03 13:31:50        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00103849, over full image : 0.00343392
03-May-2024 13:32:08 2024-05-03 13:31:50        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 13979, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
03-May-2024 13:32:08 2024-05-03 13:31:50        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=13979, Gain=0.1
03-May-2024 13:32:08 2024-05-03 13:31:50        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:32:08 2024-05-03 13:31:50        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00317392 within the mask
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()         Diverging due to large scale?
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()           0    0
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()           1    0
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()           2    0.000317392
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        MatrixCleaner::clean()           3    0.00237763
03-May-2024 13:32:08 2024-05-03 13:31:51        WARN        SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185)        MSClean minor cycle stopped at large scale negative or diverging
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->2 [2], model=8.04996->8.0502, peakres=0.00103849->0.00103466, Exited multiscale minor cycle without reaching any stopping criterion.
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 6023 iterations.
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:32:08 2024-05-03 13:31:51        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:32:08
03-May-2024 13:32:08 0%....10....20....30....40.
03-May-2024 13:32:08 0%....10....20....30....40
03-May-2024 13:32:08 0%....10....20.
03-May-2024 13:32:08 0%....10....20....30....40.
03-May-2024 13:32:08 0%....10....20....30....
03-May-2024 13:32:08 0%....10....20.
03-May-2024 13:32:08 0%....10....20.40..............30.......3050.50.50...50.....30..........40...40......60.60...60.....4060.........50...50.....70...70.....7070.......50.....60.60.......80...80..80..80.............6070......70..90...90.9090......100%
03-May-2024 13:32:08 ........100%
03-May-2024 13:32:08 ..7080..100%
03-May-2024 13:32:08 ...100%
03-May-2024 13:32:08 ........80...90..80...100%
03-May-2024 13:32:08 ...90......100%
03-May-2024 13:32:08 .90....100%
03-May-2024 13:32:08 2024-05-03 13:32:09        INFO        tclean::::casa        ...done
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00103497, over full image : 0.00343497
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 13977, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=13977, Gain=0.1
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0237827 within the mask
03-May-2024 13:32:28 2024-05-03 13:32:09        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        MatrixCleaner::clean()         Reached stopping threshold 0.001 at iteration 79
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        MatrixCleaner::clean()         Optimum flux is 0.000999499
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        MatrixCleaner::clean()           0    0.00171772
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        MatrixCleaner::clean()           1    0.0010762
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        MatrixCleaner::clean()           2    0.013895
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        MatrixCleaner::clean()           3    0.0208309
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->80 [80], model=8.05028->8.08764, peakres=0.00103497->0.000999499, Reached cyclethreshold.
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 6103 iterations.
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:32:28 2024-05-03 13:32:10        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:32:28
03-May-2024 13:32:28 0%....10....20....30....40
03-May-2024 13:32:28 0%....10....20....30....40
03-May-2024 13:32:28 0%....10....20.
03-May-2024 13:32:28 0%....10....20
03-May-2024 13:32:28 0%....10....20.
03-May-2024 13:32:28 0%....10....20....30....
03-May-2024 13:32:28 0%....10....20....30.....40..40................30.....305050..50.....50....30.........40...40.....60..60.60.......4060.........50....50....70..70......7070.......50.....60.60......80....80.80...80.............60.70.....70..90...90.9090.......100%
03-May-2024 13:32:28 ...100%
03-May-2024 13:32:28 ......7080..100%
03-May-2024 13:32:28 ...100%
03-May-2024 13:32:28 ........80...90..80...100%
03-May-2024 13:32:28 ...90......100%
03-May-2024 13:32:28 .90....100%
03-May-2024 13:32:28 2024-05-03 13:32:28        INFO        tclean::::casa        ...done
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00101199, over full image : 0.00338568
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 13897, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=13897, Gain=0.1
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::clean()         Cleaning using given mask
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::clean()         Starting iteration
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0029728 within the mask
03-May-2024 13:32:46 2024-05-03 13:32:28        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        MatrixCleaner::clean()         Reached stopping threshold 0.001 at iteration 14
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        MatrixCleaner::clean()         Optimum flux is 0.000997133
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        MatrixCleaner::clean()           0    0.000502166
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        MatrixCleaner::clean()           1    0.000133752
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        MatrixCleaner::clean()           2    0.0026557
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        MatrixCleaner::clean()           3    0
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->15 [15], model=8.0877->8.09082, peakres=0.00101199->0.000997133, Reached cyclethreshold.
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 6118 iterations.
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
03-May-2024 13:32:46 2024-05-03 13:32:29        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:32:46
03-May-2024 13:32:46 0%....10....20....30....40
03-May-2024 13:32:46 0%....10....20.
03-May-2024 13:32:46 0%....10....20....30....40
03-May-2024 13:32:46 0%....10....20....30....40.
03-May-2024 13:32:46 0%....10....20....30....40.
03-May-2024 13:32:46 0%....10....20.
03-May-2024 13:32:46 0%....10....20................30......3050.5050....50.....30..........40....40...60...60...60.....4060...........50.50..70......70.....7070.......50.....60.60.....80.....80...80.80.............60.70.....70.90....90.9090......100%
03-May-2024 13:32:46 .......100%
03-May-2024 13:32:46 ...70..100%
03-May-2024 13:32:46 80...100%
03-May-2024 13:32:46 ........80...90..80...100%
03-May-2024 13:32:46 ...90......100%
03-May-2024 13:32:46 .90....100%
03-May-2024 13:32:46 2024-05-03 13:32:47        INFO        tclean::::casa        ...done
03-May-2024 13:33:09 2024-05-03 13:32:47        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
03-May-2024 13:33:09 2024-05-03 13:32:47        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.000998028, over full image : 0.00338169
03-May-2024 13:33:09 2024-05-03 13:32:47        INFO        tclean::::casa        Reached global stopping criterion : threshold
03-May-2024 13:33:09 2024-05-03 13:32:47        INFO        tclean::::casa        getSummary call: fullsummary=False
03-May-2024 13:33:09 2024-05-03 13:32:47        INFO        SDAlgorithmBase::restore         [3c391_ctm_spw0_multiscale] : Restoring model image.
03-May-2024 13:33:09 2024-05-03 13:32:47        INFO        SIImageStore::restore         Beam for chan : 0 : 17.0639 arcsec, 14.9556 arcsec, 21.6315 deg
03-May-2024 13:33:09 2024-05-03 13:33:08        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        tclean::::casa        ...done
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        tclean::::casa        Searching for images with prefix '3c391_ctm_spw0_multiscale'... Found these, writing history into them: ['3c391_ctm_spw0_multiscale.weight', '3c391_ctm_spw0_multiscale.model', '3c391_ctm_spw0_multiscale.psf', '3c391_ctm_spw0_multiscale.sumwt', '3c391_ctm_spw0_multiscale.pb', '3c391_ctm_spw0_multiscale.residual', '3c391_ctm_spw0_multiscale.gridwt_moswt', '3c391_ctm_spw0_multiscale.mask', '3c391_ctm_spw0_multiscale.image']
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        tclean::::casa        Task tclean complete. Start time: 2024-05-03 13:27:08.612538 End time: 2024-05-03 13:33:08.845482
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        tclean::::casa        ##### End Task: tclean               #####
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        tclean::::casa        ##########################################
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::::casa        ##########################################
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::::casa        ##### Begin Task: impbcor            #####
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::::casa        impbcor( imagename='3c391_ctm_spw0_multiscale.image', pbimage='3c391_ctm_spw0_multiscale.pb', outfile='3c391_ctm_spw0_multiscale.pbcorimage', overwrite=False, box='', region='', chans='', stokes='', mask='', mode='divide', cutoff=-1.0, stretch=False )
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::CasacRegionManager::fromBCS        Using all spectral channels.
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::CasacRegionManager::fromBCS        Using polarizations ALL
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::ImageRegion::fromRecord        Selected bounding box :
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::ImageRegion::fromRecord+            [0, 0, 0, 0] to [479, 479, 0, 0]  (18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz to 18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz)
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::ImageRegion::fromRecord        Selected bounding box :
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::ImageRegion::fromRecord+            [0, 0, 0, 0] to [479, 479, 0, 0]  (18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz to 18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz)
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::SubImageFactory::createImage        Creating image '3c391_ctm_spw0_multiscale.pbcorimage' of shape [480, 480, 1, 1]
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::ImageMaskAttacher::makeMask        Created mask `mask0'
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::::casa        Task impbcor complete. Start time: 2024-05-03 13:33:08.852814 End time: 2024-05-03 13:33:08.976668
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::::casa        ##### End Task: impbcor              #####
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        impbcor::::casa        ##########################################
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::casa        ##########################################
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::casa        ##### Begin Task: imstat             #####
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::casa        imstat( imagename='3c391_ctm_spw0_multiscale.pbcorimage', axes=[], region='', box='', chans='', stokes='', listit=True, verbose=True, mask='', stretch=False, logfile='', append=True, algorithm='classic', fence=-1.0, center='mean', lside=True, zscore=-1.0, maxiter=-1, clmethod='auto', niter=3 )
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::CasacRegionManager::fromBCS        Using all spectral channels.
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::CasacRegionManager::fromBCS        Using polarizations ALL
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::image::statistics         Determining stats for image 3c391_ctm_spw0_multiscale.pbcorimage
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::ImageRegion::fromRecord        Selected bounding box :
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::ImageRegion::fromRecord+            [0, 0, 0, 0] to [479, 479, 0, 0]  (18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz to 18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz)
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup        Statistics calculated using Classic algorithm
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup        Regions ---
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup                 -- bottom-left corner (pixel) [blc]:  [0, 0, 0, 0]
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup                 -- top-right corner (pixel) [trc]:    [479, 479, 0, 0]
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup                 -- bottom-left corner (world) [blcf]: 18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup                 -- top-right corner (world) [trcf]:   18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::        Values ---
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- flux density [flux]:                    9.38133 Jy
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- number of points [npts]:                115808
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- maximum value [max]:                    0.155531 Jy/beam
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- minimum value [min]:                    -0.00650507 Jy/beam
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- position of max value (pixel) [maxpos]: [288, 256, 0, 0]
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- position of min value (pixel) [minpos]: [70, 141, 0, 0]
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- position of max value (world) [maxposf]: 18:49:16.243, -00.55.00.580, I, 4.59835e+09Hz
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- position of min value (world) [minposf]: 18:49:52.582, -00.59.48.073, I, 4.59835e+09Hz
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- Sum of pixel values [sum]:               434.042 Jy/beam
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                 -- Sum of squared pixel values [sumsq]:     17.8575 Jy/beam.Jy/beam
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::        Statistics ---
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                -- Mean of the pixel values [mean]:         0.00374795 Jy/beam
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                -- Variance of the pixel values :           0.000140153 Jy/beam
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                -- Standard deviation of the Mean [sigma]:  0.0118386 Jy/beam
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                -- Root mean square [rms]:                  0.0124177 Jy/beam
03-May-2024 13:33:09 2024-05-03 13:33:09        INFO        imstat::::                -- Median of the pixel values [median]:     0.000431382 Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- Median of the deviations [medabsdevmed]: 0.00135308 Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- IQR [quartile]:                          0.00292644 Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- First quartile [q1]:                     -0.000747782 Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- Third quartile [q3]:                     0.00217866 Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics        Sum column unit = Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics+        Mean column unit = Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics+        Std_dev column unit = Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics+        Minimum column unit = Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics+        Maximum column unit = Jy/beam
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics        Npts          Sum           Mean          Rms           Std_dev       Minimum       Maximum     
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics         1.158080e+05  4.340422e+02  3.747946e-03  1.241768e-02  1.183862e-02 -6.505066e-03  1.555314e-01
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::casa        Task imstat complete. Start time: 2024-05-03 13:33:08.982976 End time: 2024-05-03 13:33:09.002000
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::casa        ##### End Task: imstat               #####
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::casa        ##########################################
03-May-2024 13:33:31 Expected image peak at pixels [288 256], got [288 256], tolerance exact
03-May-2024 13:33:31 Expected pbcor image peak of 0.15553903579711914, got 0.1555313915014267, tolerance 4.0%
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::casa        ##########################################
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::casa        ##### Begin Task: imstat             #####
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::casa        imstat( imagename='3c391_ctm_spw0_multiscale.residual', axes=[], region='3c391_clean_mask.crtf', box='', chans='', stokes='', listit=True, verbose=True, mask='', stretch=False, logfile='', append=True, algorithm='classic', fence=-1.0, center='mean', lside=True, zscore=-1.0, maxiter=-1, clmethod='auto', niter=3 )
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::RegionTextParser::_determineVersion        RegionTextParser::_determineVersion: Found spec version 0
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::CasacRegionManager::fromBCS        Region read from CRTF file 3c391_clean_mask.crtf
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::image::statistics         Determining stats for image 3c391_ctm_spw0_multiscale.residual
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageRegion::fromRecord        Selected bounding box :
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageRegion::fromRecord+            [140, 145, 0, 0] to [316, 315, 0, 0]  (18:49:40.913, -00.59.38.078, I, 4.59835e+09Hz to 18:49:11.576, -00.52.33.079, I, 4.59835e+09Hz)
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup        Statistics calculated using Classic algorithm
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup        Regions ---
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup                 -- bottom-left corner (pixel) [blc]:  [140, 145, 0, 0]
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup                 -- top-right corner (pixel) [trc]:    [316, 315, 0, 0]
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup                 -- bottom-left corner (world) [blcf]: 18:49:40.913, -00.59.38.078, I, 4.59835e+09Hz
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::_logStartup                 -- top-right corner (world) [trcf]:   18:49:11.576, -00.52.33.079, I, 4.59835e+09Hz
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::        Values ---
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                 -- number of points [npts]:                19718
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                 -- maximum value [max]:                    0.000998028
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                 -- minimum value [min]:                    -0.000931884
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                 -- position of max value (pixel) [maxpos]: [258, 314, 0, 0]
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                 -- position of min value (pixel) [minpos]: [151, 157, 0, 0]
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                 -- position of max value (world) [maxposf]: 18:49:21.244, -00.52.35.580, I, 4.59835e+09Hz
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                 -- position of min value (world) [minposf]: 18:49:39.080, -00.59.08.078, I, 4.59835e+09Hz
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                 -- Sum of pixel values [sum]:               8.29611
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                 -- Sum of squared pixel values [sumsq]:     0.00577237
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::        Statistics ---
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- Mean of the pixel values [mean]:         0.000420738
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- Variance of the pixel values :           1.15732e-07
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- Standard deviation of the Mean [sigma]:  0.000340194
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- Root mean square [rms]:                  0.00054106
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- Median of the pixel values [median]:     0.00045869
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- Median of the deviations [medabsdevmed]: 0.000227924
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- IQR [quartile]:                          0.000456529
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- First quartile [q1]:                     0.000223351
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::                -- Third quartile [q3]:                     0.00067988
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics        Sum column unit =
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics+        Mean column unit =
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics+        Std_dev column unit =
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics+        Minimum column unit =
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics+        Maximum column unit =
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics        Npts          Sum           Mean          Rms           Std_dev       Minimum       Maximum     
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::ImageStatsCalculator::statistics         1.971800e+04  8.296111e+00  4.207380e-04  5.410604e-04  3.401938e-04 -9.318842e-04  9.980279e-04
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::casa        Task imstat complete. Start time: 2024-05-03 13:33:09.004665 End time: 2024-05-03 13:33:09.021170
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::casa        ##### End Task: imstat               #####
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        imstat::::casa        ##########################################
03-May-2024 13:33:31 Expected peak residual of 0.0010077510960400105, got 0.0009980278555303812, tolerance 10.0%
03-May-2024 13:33:31 Expected rms residual of 0.000542584067811474, got 0.0005410604187317684, tolerance 10.0%
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        name::        No table opened.
03-May-2024 13:33:31 2024-05-03 13:33:09        INFO        name::        No table opened.
03-May-2024 13:33:31 .
03-May-2024 13:33:31 ----------------------------------------------------------------------
03-May-2024 13:33:31 Ran 1 test in 879.105s
03-May-2024 13:33:31
03-May-2024 13:33:31 OK
03-May-2024 13:33:31 Executor result: 0
03-May-2024 13:33:31 Failed tests:[]
03-May-2024 13:33:31
03-May-2024 13:33:31 Results: [{'testname': 'test_stk_vla_users_continuum_from_SDM', 'runtime': '0:15:00.223420', 'returncode': 0, 'testerr': []}]
03-May-2024 13:33:31 Stopping Xvfb.
03-May-2024 13:33:31 Stopping framebuffer <Popen: returncode: None args: ['Xvfb', ':87', '-screen', '0', '2048x2048x24...>
03-May-2024 13:33:31 Virtual frame buffer deployed at :87 with pid 100 successfully shutdown
03-May-2024 13:33:31 Xvfb stopped.
03-May-2024 13:33:32 cp: cannot stat '/home/casatest/work/test_stk_vla_users_continuum_from_SDM/*.png': No such file or directory
03-May-2024 13:33:32 total 1247548
03-May-2024 13:33:32 -rw-r--r-- 1 casaci    15001 1277481912 May  3 13:16 casa-6.6.4-21-py3.8.el8.tar.xz
03-May-2024 13:33:32 drwxr-xr-x 2 casaci root             24 May  3 13:33 html
03-May-2024 13:33:32 -rw-r--r-- 1 casaci casatest        388 May  3 13:33 xUnit-suite-mpi-8.xml
03-May-2024 13:33:32 total 12
03-May-2024 13:33:32 -rw-r--r-- 1 casaci root 11851 May  3 13:33 index.html
03-May-2024 13:33:32 Finished task 'Script' with result: Success
03-May-2024 13:33:32 Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
03-May-2024 13:33:32 Parsing test results under /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMTS-STCR85D...
03-May-2024 13:33:32 Finished task 'Parse test results' with result: Success
03-May-2024 13:33:32
Substituting variable: ${bamboo.capability.hostname} with cvpost134
03-May-2024 13:33:32
Substituting variable: ${bamboo.capability.hostname} with cvpost134
03-May-2024 13:33:32 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
03-May-2024 13:33:32
Beginning to execute external process for build 'CASA - CMake Master Test Stakeholder - VLA Stakeholder Tests MPI EL8 Docker #42 (CASA-CMTS-STCR85D-42)'
... running command line:
/export/cvpost134-nvme/casa/bamboohome/temp/CASA-CMTS-STCR85D-42-ScriptBuildTask-7212464678475270486.sh
... in: /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMTS-STCR85D
03-May-2024 13:33:32 Starting cleanup
03-May-2024 13:33:42 test-container
03-May-2024 13:33:48 test-container
03-May-2024 13:33:48 Cleanup complete
03-May-2024 13:33:48 Finished task 'Cleanup' with result: Success
03-May-2024 13:33:48 Running post build plugin 'Docker Container Cleanup'
03-May-2024 13:33:48 Running post build plugin 'NCover Results Collector'
03-May-2024 13:33:48 Running post build plugin 'Build Results Label Collector'
03-May-2024 13:33:48 Running post build plugin 'Clover Results Collector'
03-May-2024 13:33:48 Running post build plugin 'npm Cache Cleanup'
03-May-2024 13:33:48 Running post build plugin 'Artifact Copier'
03-May-2024 13:33:48 Publishing an artifact: VLA Stakeholder MPI
03-May-2024 13:33:48 Finished publishing of artifact Non required job artifact Http Compression On : [VLA Stakeholder MPI], patterns: [html/**] anchored at: [dockershare] in 66.57 ms
03-May-2024 13:33:48 Finalising the build...
03-May-2024 13:33:48 Stopping timer.
03-May-2024 13:33:48 Build CASA-CMTS-STCR85D-42 completed.
03-May-2024 09:33:48 Running on server: post build plugin 'Build Hanging Detection Configuration'
03-May-2024 09:33:48 Running on server: post build plugin 'NCover Results Collector'
03-May-2024 09:33:48 Running on server: post build plugin 'Build Labeller'
03-May-2024 09:33:48 Running on server: post build plugin 'Clover Delta Calculator'
03-May-2024 09:33:48 Running on server: post build plugin 'Maven Dependencies Postprocessor'
03-May-2024 09:33:48 All post build plugins have finished
03-May-2024 09:33:48 Generating build results summary...
03-May-2024 09:33:48 Saving build results to disk...
03-May-2024 09:33:48 Store variable context...
03-May-2024 09:33:48 Finished building CASA-CMTS-STCR85D-42.