Build: #2 was successful

Job: Test Casalith Cvpost Many Linux 2.28 was successful

Stages & jobs

  1. Default Stage

Build log

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

02-Jul-2026 21:02:49 2026-07-02 21:02:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:02:49 2026-07-02 21:02:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:02:49 2026-07-02 21:02:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
02-Jul-2026 21:02:49 2026-07-02 21:02:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [10, 10, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:02:49 2026-07-02 21:02:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] with ftmachine : gridft
02-Jul-2026 21:02:49 2026-07-02 21:02:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 2.124e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:02:49 2026-07-02 21:02:44        INFO        tclean::::casa        ...done
02-Jul-2026 21:02:49 2026-07-02 21:02:47        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:02:49 2026-07-02 21:02:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
02-Jul-2026 21:02:49 2026-07-02 21:02:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
02-Jul-2026 21:02:49 2026-07-02 21:02:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
02-Jul-2026 21:02:49 2026-07-02 21:02:48        INFO        tclean::::casa        ...done
02-Jul-2026 21:02:49 2026-07-02 21:02:48        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:02:49 2026-07-02 21:02:49        INFO        tclean::::casa        ...done
02-Jul-2026 21:02:49 2026-07-02 21:02:49        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:02:49 2026-07-02 21:02:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:02:49 2026-07-02 21:02:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:02:49 2026-07-02 21:02:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:02:49 2026-07-02 21:02:50        INFO        tclean::::casa        ...done
02-Jul-2026 21:02:49 2026-07-02 21:02:50        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:02:49 2026-07-02 21:02:50        INFO        SIImageStore::calcSensitivity         [tst] Theoretical sensitivity (Jy/bm):0.00102323
02-Jul-2026 21:02:49 2026-07-02 21:02:50        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.012123
02-Jul-2026 21:02:49 2026-07-02 21:02:50        INFO        SIImageStore::printBeamSet         Beam : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:03:00 2026-07-02 21:02:50        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:00 2026-07-02 21:02:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::makePrimaryBeam::MPIServer-1         vi2 : Evaluating Primary Beam model onto image grid(s)
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3]
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model does not exist. No initial prediction will be done
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model does not exist. No initial prediction will be done
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model does not exist. No initial prediction will be done
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:03:00 2026-07-02 21:02:51        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:03:00 2026-07-02 21:02:52        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:00 2026-07-02 21:02:52        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:03:00 2026-07-02 21:02:59        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:00 2026-07-02 21:02:59        INFO        ::casa::MPIServer-2        node 2 has completed its job
02-Jul-2026 21:03:00 2026-07-02 21:02:59        INFO        ::casa::MPIServer-1        node 1 has completed its job
02-Jul-2026 21:03:00 2026-07-02 21:02:59        INFO        ::casa::MPIServer-3        node 3 has completed its job
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        tclean::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf', 'tst.sumwt', 'tst.pb', 'tst.residual']
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-02 17:02:36.909784 End time: 2026-07-02 17:03:00.185684
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        tclean::::casa        ##### End Task: tclean               #####
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : hogbom
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1.01018
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.01018, over full image : 1.01018
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        SDAlgorithmBase::deconvolve         [tst] Run Hogbom minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.814305, peakres=1.01018->0.598541, Reached cycleniter.
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.598541, over full image : 0.598541
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:03:00 2026-07-02 21:03:00        INFO        SIImageStore::restore         Beam for chan : 0 : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf', 'tst.sumwt', 'tst.pb', 'tst.residual', 'tst.mask', 'tst.model', 'tst.image']
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:03:00.193733 End time: 2026-07-02 17:03:00.710661
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : hogbom
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.598541, over full image : 0.598541
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing to existing mask
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.598541, over full image : 0.598541
02-Jul-2026 21:03:00 2026-07-02 21:03:01        INFO        SDAlgorithmBase::deconvolve         [tst] Run Hogbom minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0.814305->1.40174, peakres=0.598541->0.470332, Reached cycleniter.
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.470332, over full image : 0.470332
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        SIImageStore::restore         Beam for chan : 0 : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf', 'tst.sumwt', 'tst.pb', 'tst.residual', 'tst.mask', 'tst.model', 'tst.image']
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:03:00.723793 End time: 2026-07-02 17:03:01.095571
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:03:08 2026-07-02 21:03:01        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:03:08 .2026-07-02 21:03:02        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:03:08 2026-07-02 21:03:02        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:03:08 2026-07-02 21:03:02        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:03:08 2026-07-02 21:03:04        INFO        deconvolve::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:03:08 /export/cvpost134-4tb/casa/casa-data/casatestdata/unittest/deconvolve/
02-Jul-2026 21:03:08 refim_eptwochan
02-Jul-2026 21:03:08 2026-07-02 21:03:04        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:03:08 2026-07-02 21:03:04        INFO        tclean::::casa        ##### Begin Task: tclean             #####
02-Jul-2026 21:03:08 2026-07-02 21:03:04        INFO        tclean::::casa        tclean( vis='refim_eptwochan.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=10, cell='8.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='standard', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=0.2, normtype='flatnoise', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=False, restoringbeam=[], pbcor=True, outlierfile='', weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
02-Jul-2026 21:03:08 2026-07-02 21:03:04        WARN        tclean::task_tclean::casa        For specmode='mfs' and deconvolver='mtmfs', the option of pbcor=True divides each restored Taylor coefficient image by the pb.tt0 image. This correction ignores the frequency-dependence of the primary beam and does not correct for PB spectral index. It is scientifically valid only for small fractional bandwidths. For more accurate wideband primary beam correction (if needed), please use one of the following options : (1) specmode='mvc' with gridder='standard' or 'mosaic' with pbcor=True,  (2) conjbeams=True and wbawp=True with gridder='awproject' and pbcor=True.
02-Jul-2026 21:03:08 2026-07-02 21:03:04        INFO        tclean::::casa        Verifying Input Parameters
02-Jul-2026 21:03:08 2026-07-02 21:03:05        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:03:08 2026-07-02 21:03:05        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:03:08 2026-07-02 21:03:05        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisUtilMethods::continuumDataPartition         
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 112323 168480 [P][M]: 0:2 ROWNUMBER() >= 112323 && ROWNUMBER() <= 168480
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_eptwochan.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 56161', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_eptwochan.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 56162 && ROWNUMBER() <= 112322', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_eptwochan.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 112323 && ROWNUMBER() <= 168480', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisImagerVi2::selectData         MS : refim_eptwochan.ms | [Opened in readonly mode]
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisImagerVi2::selectData           NRows selected : 168480
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisImagerVi2::defineImage         Impars: start
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisImagerVi2::defineImage         Shape: [10, 10, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : gridft
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 3.541e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:03:08 2026-07-02 21:03:07        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:08 2026-07-02 21:03:08        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:08 2026-07-02 21:03:08        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:08 2026-07-02 21:03:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_eptwochan.ms | [Opened in readonly mode]
02-Jul-2026 21:03:08 2026-07-02 21:03:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_eptwochan.ms | [Opened in readonly mode]
02-Jul-2026 21:03:08 2026-07-02 21:03:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 56162 && ROWNUMBER() <= 112322 |   NRows selected : 56161
02-Jul-2026 21:03:08 2026-07-02 21:03:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_eptwochan.ms | [Opened in readonly mode]
02-Jul-2026 21:03:18 2026-07-02 21:03:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 56161 |   NRows selected : 56161
02-Jul-2026 21:03:18 2026-07-02 21:03:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 112323 && ROWNUMBER() <= 168480 |   NRows selected : 56158
02-Jul-2026 21:03:18 2026-07-02 21:03:09        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] :
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] :
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [10, 10, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] with ftmachine : gridft
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 3.541e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] :
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] :
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [10, 10, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] with ftmachine : gridft
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 3.541e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:03:18 2026-07-02 21:03:10        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:03:18 2026-07-02 21:03:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
02-Jul-2026 21:03:18 2026-07-02 21:03:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [10, 10, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:03:18 2026-07-02 21:03:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] with ftmachine : gridft
02-Jul-2026 21:03:18 2026-07-02 21:03:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 3.541e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:03:18 2026-07-02 21:03:11        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:18 2026-07-02 21:03:14        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:18 2026-07-02 21:03:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
02-Jul-2026 21:03:18 2026-07-02 21:03:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
02-Jul-2026 21:03:18 2026-07-02 21:03:14        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
02-Jul-2026 21:03:18 2026-07-02 21:03:15        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:18 2026-07-02 21:03:15        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:18 2026-07-02 21:03:16        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:18 2026-07-02 21:03:16        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:18 2026-07-02 21:03:16        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:03:18 2026-07-02 21:03:16        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:03:18 2026-07-02 21:03:16        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SIImageStoreMultiTerm::calcSensitivity +        [955101, -3322.56
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SIImageStoreMultiTerm::calcSensitivity +         -3322.56, 70400.6]
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SIImageStoreMultiTerm::calcSensitivity +        [1.04718e-06, 4.94218e-08
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SIImageStoreMultiTerm::calcSensitivity +         4.94218e-08, 1.42068e-05]
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor0] Theoretical sensitivity (Jy/bm):0.00102332
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor1] Theoretical sensitivity (Jy/bm):0.00376919
02-Jul-2026 21:03:18 2026-07-02 21:03:18        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749992 GHz -> 2.24998GHz.Fractional Bandwidth : 100 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
02-Jul-2026 21:03:35 2026-07-02 21:03:18        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.012182
02-Jul-2026 21:03:35 2026-07-02 21:03:18        INFO        SIImageStore::printBeamSet         Beam : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:03:35 2026-07-02 21:03:18        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:35 2026-07-02 21:03:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::makePrimaryBeam::MPIServer-1         vi2 : Evaluating Primary Beam model onto image grid(s)
02-Jul-2026 21:03:35 2026-07-02 21:03:19        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3]
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt0 does not exist. No initial prediction will be done for term0
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt1 does not exist. No initial prediction will be done for term1
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt0 does not exist. No initial prediction will be done for term0
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt1 does not exist. No initial prediction will be done for term1
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt0 does not exist. No initial prediction will be done for term0
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt1 does not exist. No initial prediction will be done for term1
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:03:35 2026-07-02 21:03:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:03:35 2026-07-02 21:03:22        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:35 2026-07-02 21:03:22        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:03:35 2026-07-02 21:03:34        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:35 2026-07-02 21:03:34        INFO        ::casa::MPIServer-2        node 2 has completed its job
02-Jul-2026 21:03:35 2026-07-02 21:03:34        INFO        ::casa::MPIServer-1        node 1 has completed its job
02-Jul-2026 21:03:35 2026-07-02 21:03:34        INFO        ::casa::MPIServer-3        node 3 has completed its job
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        tclean::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf.tt0', 'tst.sumwt.tt0', 'tst.psf.tt1', 'tst.sumwt.tt1', 'tst.psf.tt2', 'tst.sumwt.tt2', 'tst.pb.tt0', 'tst.residual.tt0', 'tst.residual.tt1']
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-02 17:03:03.832161 End time: 2026-07-02 17:03:35.155141
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        tclean::::casa        ##### End Task: tclean               #####
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : mtmfs
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1.01018
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.01018, over full image : 1.01018
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        SDAlgorithmBase::deconvolve         [tst] Run mtmfs minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 10 pixels on each side for minor-cycle updates.
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [5, 5]
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749992 GHz -> 2.24998GHz.Fractional Bandwidth : 100 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [1, -0.00347875
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00347875, 0.0737101]
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 1.01018
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO                [10] Res: 0.620616 Max: 0.503391 Gain: 0.1 Pos: [0, 9] Scale: 0 Coeffs: 0.611987  -1.29365 
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0.744336 (in this run)
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 0.744336  [1]: -1.54821
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.744336, peakres=1.01018->0.48471, Reached cycleniter.
02-Jul-2026 21:03:35 2026-07-02 21:03:35        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:03:36 2026-07-02 21:03:35        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.48471, over full image : 0.48471
02-Jul-2026 21:03:36 2026-07-02 21:03:35        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:03:36 2026-07-02 21:03:35        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
02-Jul-2026 21:03:36 2026-07-02 21:03:35        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SIImageStore::restore         Beam for chan : 0 : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SIImageStore::restore         Beam for chan : 0 : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.048142 Jy/beam
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.alpha.error
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SDAlgorithmMSMFS::restore         Restored images :
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SDAlgorithmMSMFS::restore         /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.image.tt0  (model=/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.model.tt0)
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SDAlgorithmMSMFS::restore         /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.image.tt1  (model=/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.model.tt1)
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SDAlgorithmMSMFS::restore         
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf.tt0', 'tst.sumwt.tt0', 'tst.psf.tt1', 'tst.sumwt.tt1', 'tst.psf.tt2', 'tst.sumwt.tt2', 'tst.pb.tt0', 'tst.residual.tt0', 'tst.residual.tt1', 'tst.mask', 'tst.model.tt0', 'tst.model.tt1', 'tst.image.tt0', 'tst.image.tt1', 'tst.alpha', 'tst.alpha.error']
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:03:35.169015 End time: 2026-07-02 17:03:36.321305
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : mtmfs
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.48471, over full image : 0.48471
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing to existing mask
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.48471, over full image : 0.48471
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SDAlgorithmBase::deconvolve         [tst] Run mtmfs minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 10 pixels on each side for minor-cycle updates.
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [5, 5]
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749992 GHz -> 2.24998GHz.Fractional Bandwidth : 100 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [1, -0.00347875
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00347875, 0.0737101]
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 0.48471
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO                [10] Res: 0.316589 Max: 0.132319 Gain: 0.1 Pos: [5, 9] Scale: 0 Coeffs: 0.338361  -0.476127 
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0.402002 (in this run)
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 1.14634  [1]: -2.22354
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0.744336->1.14634, peakres=0.48471->0.288126, Reached cycleniter.
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.288126, over full image : 0.288126
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
02-Jul-2026 21:03:36 2026-07-02 21:03:36        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:03:36 2026-07-02 21:03:37        INFO        SIImageStore::restore         Beam for chan : 0 : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:03:36 2026-07-02 21:03:37        INFO        SIImageStore::restore         Beam for chan : 0 : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:03:36 2026-07-02 21:03:37        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.0286807 Jy/beam
02-Jul-2026 21:03:36 2026-07-02 21:03:37        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.alpha.error
02-Jul-2026 21:03:36 2026-07-02 21:03:37        INFO        SDAlgorithmMSMFS::restore         Restored images :
02-Jul-2026 21:03:36 2026-07-02 21:03:37        INFO        SDAlgorithmMSMFS::restore         /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.image.tt0  (model=/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.model.tt0)
02-Jul-2026 21:03:36 2026-07-02 21:03:37        INFO        SDAlgorithmMSMFS::restore         /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.image.tt1  (model=/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.model.tt1)
02-Jul-2026 21:03:36 2026-07-02 21:03:37        INFO        SDAlgorithmMSMFS::restore         
02-Jul-2026 21:03:45 2026-07-02 21:03:37        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf.tt0', 'tst.sumwt.tt0', 'tst.psf.tt1', 'tst.sumwt.tt1', 'tst.psf.tt2', 'tst.sumwt.tt2', 'tst.pb.tt0', 'tst.residual.tt0', 'tst.residual.tt1', 'tst.mask', 'tst.model.tt0', 'tst.model.tt1', 'tst.image.tt0', 'tst.image.tt1', 'tst.alpha', 'tst.alpha.error']
02-Jul-2026 21:03:45 2026-07-02 21:03:37        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:03:36.344344 End time: 2026-07-02 17:03:37.164757
02-Jul-2026 21:03:45 2026-07-02 21:03:37        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:03:45 2026-07-02 21:03:37        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:03:45 .2026-07-02 21:03:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:03:45 2026-07-02 21:03:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:03:45 2026-07-02 21:03:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:03:45 2026-07-02 21:03:40        INFO        deconvolve::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:03:45 /export/cvpost134-4tb/casa/casa-data/casatestdata/unittest/deconvolve/
02-Jul-2026 21:03:45 refim_eptwochan
02-Jul-2026 21:03:45 2026-07-02 21:03:40        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:03:45 2026-07-02 21:03:40        INFO        tclean::::casa        ##### Begin Task: tclean             #####
02-Jul-2026 21:03:45 2026-07-02 21:03:40        INFO        tclean::::casa        tclean( vis='refim_eptwochan.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=10, cell='8.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='standard', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=0.2, normtype='flatnoise', deconvolver='multiscale', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=False, restoringbeam=[], pbcor=True, outlierfile='', weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
02-Jul-2026 21:03:45 2026-07-02 21:03:40        INFO        tclean::::casa        Verifying Input Parameters
02-Jul-2026 21:03:45 2026-07-02 21:03:41        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:03:45 2026-07-02 21:03:41        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:03:45 2026-07-02 21:03:41        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisUtilMethods::continuumDataPartition         
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 112323 168480 [P][M]: 0:2 ROWNUMBER() >= 112323 && ROWNUMBER() <= 168480
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_eptwochan.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 56161', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_eptwochan.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 56162 && ROWNUMBER() <= 112322', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_eptwochan.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 112323 && ROWNUMBER() <= 168480', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisImagerVi2::selectData         MS : refim_eptwochan.ms | [Opened in readonly mode]
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisImagerVi2::selectData           NRows selected : 168480
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisImagerVi2::defineImage         Impars: start
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisImagerVi2::defineImage         Shape: [10, 10, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : gridft
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 2.124e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:03:45 2026-07-02 21:03:43        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:45 2026-07-02 21:03:44        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:45 2026-07-02 21:03:44        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:45 2026-07-02 21:03:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_eptwochan.ms | [Opened in readonly mode]
02-Jul-2026 21:03:45 2026-07-02 21:03:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 56161 |   NRows selected : 56161
02-Jul-2026 21:03:45 2026-07-02 21:03:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_eptwochan.ms | [Opened in readonly mode]
02-Jul-2026 21:03:45 2026-07-02 21:03:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_eptwochan.ms | [Opened in readonly mode]
02-Jul-2026 21:03:45 2026-07-02 21:03:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 112323 && ROWNUMBER() <= 168480 |   NRows selected : 56158
02-Jul-2026 21:03:45 2026-07-02 21:03:44        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 56162 && ROWNUMBER() <= 112322 |   NRows selected : 56161
02-Jul-2026 21:03:45 2026-07-02 21:03:45        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:45 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] :
02-Jul-2026 21:03:45 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] :
02-Jul-2026 21:03:45 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
02-Jul-2026 21:03:45 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [10, 10, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:03:55 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] with ftmachine : gridft
02-Jul-2026 21:03:55 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 2.124e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:03:55 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] :
02-Jul-2026 21:03:55 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] :
02-Jul-2026 21:03:55 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
02-Jul-2026 21:03:55 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [10, 10, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:03:55 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] with ftmachine : gridft
02-Jul-2026 21:03:55 2026-07-02 21:03:46        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 2.124e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:03:55 2026-07-02 21:03:46        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:55 2026-07-02 21:03:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:03:55 2026-07-02 21:03:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:03:55 2026-07-02 21:03:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
02-Jul-2026 21:03:55 2026-07-02 21:03:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [10, 10, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:03:55 2026-07-02 21:03:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] with ftmachine : gridft
02-Jul-2026 21:03:55 2026-07-02 21:03:47        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 2.124e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:03:55 2026-07-02 21:03:47        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:55 2026-07-02 21:03:50        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:55 2026-07-02 21:03:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
02-Jul-2026 21:03:55 2026-07-02 21:03:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
02-Jul-2026 21:03:55 2026-07-02 21:03:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
02-Jul-2026 21:03:55 2026-07-02 21:03:51        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:55 2026-07-02 21:03:51        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:55 2026-07-02 21:03:52        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:55 2026-07-02 21:03:52        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:55 2026-07-02 21:03:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:03:55 2026-07-02 21:03:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:03:55 2026-07-02 21:03:52        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:03:55 2026-07-02 21:03:53        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:55 2026-07-02 21:03:53        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:03:55 2026-07-02 21:03:53        INFO        SIImageStore::calcSensitivity         [tst] Theoretical sensitivity (Jy/bm):0.00102323
02-Jul-2026 21:03:55 2026-07-02 21:03:53        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.012094
02-Jul-2026 21:03:55 2026-07-02 21:03:53        INFO        SIImageStore::printBeamSet         Beam : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:03:55 2026-07-02 21:03:53        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:55 2026-07-02 21:03:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::makePrimaryBeam::MPIServer-1         vi2 : Evaluating Primary Beam model onto image grid(s)
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        tclean::::casa        ...done
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3]
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model does not exist. No initial prediction will be done
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model does not exist. No initial prediction will be done
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model does not exist. No initial prediction will be done
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:03:55 2026-07-02 21:03:54        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:03:55 2026-07-02 21:03:55        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:03 2026-07-02 21:03:55        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:04:03 2026-07-02 21:04:02        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:03 2026-07-02 21:04:02        INFO        ::casa::MPIServer-2        node 2 has completed its job
02-Jul-2026 21:04:03 2026-07-02 21:04:02        INFO        ::casa::MPIServer-1        node 1 has completed its job
02-Jul-2026 21:04:03 2026-07-02 21:04:02        INFO        ::casa::MPIServer-3        node 3 has completed its job
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        tclean::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf', 'tst.sumwt', 'tst.pb', 'tst.residual']
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-02 17:03:39.914644 End time: 2026-07-02 17:04:03.196018
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        tclean::::casa        ##### End Task: tclean               #####
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='multiscale', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : multiscale
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1.01018
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.01018, over full image : 1.01018
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        SDAlgorithmBase::deconvolve         [tst] Run multiscale minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [5, 5]
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         Cleaning using given mask
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         Starting iteration
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.878977 within the mask
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         1      0.878977      0.0878977
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         2      0.791079      0.167006
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         3      0.731054      0.240111
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         4      0.692517      0.309363
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         5      0.679882      0.377351
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         6      0.658804      0.443231
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         7      0.618738      0.505105
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         8      0.606363      0.565741
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         9      0.603722      0.626113
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         10      0.597304      0.685844
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()           0    0.685844
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        MatrixCleaner::clean()         Failed to reach stopping threshold
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.685844, peakres=1.01018->0.621048, Reached cycleniter.
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.621048, over full image : 0.621048
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:04:03 2026-07-02 21:04:03        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        SIImageStore::restore         Beam for chan : 0 : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf', 'tst.sumwt', 'tst.pb', 'tst.residual', 'tst.mask', 'tst.model', 'tst.image']
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:04:03.204094 End time: 2026-07-02 17:04:03.729744
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='multiscale', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : multiscale
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.621048, over full image : 0.621048
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing to existing mask
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.621048, over full image : 0.621048
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        SDAlgorithmBase::deconvolve         [tst] Run multiscale minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [5, 5]
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         Cleaning using given mask
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         Starting iteration
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.47986 within the mask
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         1      0.47986      0.047986
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         2      0.477306      0.0957166
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         3      0.449682      0.140685
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         4      0.431531      0.183838
02-Jul-2026 21:04:03 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         5      0.423772      0.226215
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         6      0.393304      0.265545
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         7      0.379626      0.303508
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         8      0.370928      0.340601
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         9      0.359684      0.376569
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         10      0.353973      0.411967
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()           0    0.411967
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        MatrixCleaner::clean()         Failed to reach stopping threshold
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0.685844->1.09781, peakres=0.621048->0.434713, Reached cycleniter.
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.434713, over full image : 0.434713
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        SIImageStore::restore         Beam for chan : 0 : 52.0816 arcsec, 48.8525 arcsec, -88.3554 deg
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf', 'tst.sumwt', 'tst.pb', 'tst.residual', 'tst.mask', 'tst.model', 'tst.image']
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:04:03.742326 End time: 2026-07-02 17:04:04.131046
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:12 .2026-07-02 21:04:04        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        tclean::::casa        ##### Begin Task: tclean             #####
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        tclean::::casa        tclean( vis='refim_point.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=10, cell='8.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='standard', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=0.2, normtype='flatnoise', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=False, restoringbeam=[], pbcor=True, outlierfile='', weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=False )
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        tclean::::casa        Verifying Input Parameters
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        SynthesisImagerVi2::selectData         MS : refim_point.ms | [Opened in readonly mode]
02-Jul-2026 21:04:12 2026-07-02 21:04:04        INFO        SynthesisImagerVi2::selectData           NRows selected : 168480
02-Jul-2026 21:04:12 2026-07-02 21:04:05        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:04:12 2026-07-02 21:04:05        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:04:12 2026-07-02 21:04:05        INFO        SynthesisImagerVi2::defineImage         Impars: start
02-Jul-2026 21:04:12 2026-07-02 21:04:05        INFO        SynthesisImagerVi2::defineImage         Shape: [10, 10, 1, 1] Spectral: [1.47499e+09] at [0] with increment [9.99992e+08]
02-Jul-2026 21:04:12 2026-07-02 21:04:05        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : gridft
02-Jul-2026 21:04:12 2026-07-02 21:04:05        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 2.124e-05 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:04:12 2026-07-02 21:04:05        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Natural weighting
02-Jul-2026 21:04:12 2026-07-02 21:04:05        INFO        SynthesisImager::makePSF         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:04:12 2026-07-02 21:04:07        INFO        SIImageStore::calcSensitivity         [tst] Theoretical sensitivity (Jy/bm):0.00039535
02-Jul-2026 21:04:12 2026-07-02 21:04:07        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.011664
02-Jul-2026 21:04:12 2026-07-02 21:04:07        INFO        SIImageStore::printBeamSet         Beam : 51.8097 arcsec, 48.6437 arcsec, -88.2966 deg
02-Jul-2026 21:04:12 2026-07-02 21:04:07        INFO        task_tclean::SynthesisImagerVi2::makePrimaryBeam         vi2 : Evaluating Primary Beam model onto image grid(s)
02-Jul-2026 21:04:12 2026-07-02 21:04:07        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:04:12 2026-07-02 21:04:09        INFO        tclean::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf', 'tst.sumwt', 'tst.pb', 'tst.residual']
02-Jul-2026 21:04:12 2026-07-02 21:04:09        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-02 17:04:04.282918 End time: 2026-07-02 17:04:09.122119
02-Jul-2026 21:04:12 2026-07-02 21:04:09        INFO        tclean::::casa        ##### End Task: tclean               #####
02-Jul-2026 21:04:12 2026-07-02 21:04:09        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:04:12 2026-07-02 21:04:11        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:04:12 2026-07-02 21:04:11        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:04:12 2026-07-02 21:04:11        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : hogbom
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        task_deconvolve::SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1.0601
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.0601, over full image : 1.0601
02-Jul-2026 21:04:12 2026-07-02 21:04:13        INFO        SDAlgorithmBase::deconvolve         [tst] Run Hogbom minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.727428, peakres=1.0601->0.33267, Reached cycleniter.
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.33267, over full image : 0.33267
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        SIImageStore::restore         Beam for chan : 0 : 51.8097 arcsec, 48.6437 arcsec, -88.2966 deg
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.residual', 'tst.psf', 'tst.pb', 'tst.mask', 'tst.model', 'tst.image']
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:04:12.781005 End time: 2026-07-02 17:04:13.307391
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        name::        No table opened.
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        showcache::        The Table Cache is empty.
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa        [ check_ret_structure ] Return dictionary struture check: type=reduced ( Pass : found all expected keys  )
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa+        [ check_ims ] Image made : ['tst.psf', 'tst.residual', 'tst.mask', 'tst.model', 'tst.image'] =  [True, True, True, True, True] ( Pass : should all be True )
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa+        [check_history] All expected history entries found. (Pass)
02-Jul-2026 21:04:18 2026-07-02 21:04:13        INFO        deconvolve::::casa+        [ check_pix_val ] tst.image : Value is 1.060097575187683 at [5, 5, 0, 0] ( Pass : should be 1.06 , Epsilon: 0.05)
02-Jul-2026 21:04:18 .2026-07-02 21:04:14        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:04:18 2026-07-02 21:04:14        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:04:18 2026-07-02 21:04:14        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam='100.0arcsec', niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : hogbom
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        task_deconvolve::SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1.0601
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.0601, over full image : 1.0601
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        SDAlgorithmBase::deconvolve         [tst] Run Hogbom minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.727428, peakres=1.0601->0.33267, Reached cycleniter.
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.33267, over full image : 0.33267
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        SIImageStore::restore         Common Beam : 100 arcsec, 100 arcsec, 0 deg
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.residual', 'tst.psf', 'tst.pb', 'tst.mask', 'tst.model', 'tst.image']
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:04:15.771140 End time: 2026-07-02 17:04:16.321596
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        name::        No table opened.
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        showcache::        The Table Cache is empty.
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa        [ check_ret_structure ] Return dictionary struture check: type=reduced ( Pass : found all expected keys  )
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa+        [ check_ims ] Image made : ['tst.psf', 'tst.residual', 'tst.mask', 'tst.model', 'tst.image'] =  [True, True, True, True, True] ( Pass : should all be True )
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa+        [check_history] All expected history entries found. (Pass)
02-Jul-2026 21:04:18 2026-07-02 21:04:16        INFO        deconvolve::::casa+        [ check_pix_val ] tst.image : Value is 1.4314706325531006 at [5, 5, 0, 0] ( Pass : should be 1.436 , Epsilon: 0.05)
02-Jul-2026 21:04:18 .2026-07-02 21:04:17        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:04:18 2026-07-02 21:04:17        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:04:18 2026-07-02 21:04:17        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:04:18 2026-07-02 21:04:19        INFO        deconvolve::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:04:18 2026-07-02 21:04:19        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:18 2026-07-02 21:04:19        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:04:18 2026-07-02 21:04:19        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=False, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:04:18 2026-07-02 21:04:19        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:04:18 2026-07-02 21:04:19        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : hogbom
02-Jul-2026 21:04:18 2026-07-02 21:04:19        INFO        task_deconvolve::SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1.0601
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.0601, over full image : 1.0601
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        SDAlgorithmBase::deconvolve         [tst] Run Hogbom minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.727428, peakres=1.0601->0.33267, Reached cycleniter.
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.33267, over full image : 0.33267
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.residual', 'tst.psf', 'tst.pb', 'tst.mask', 'tst.model']
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:04:18.786232 End time: 2026-07-02 17:04:19.123629
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        name::        No table opened.
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        showcache::        The Table Cache is empty.
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa        [ check_ret_structure ] Return dictionary struture check: type=reduced ( Pass : found all expected keys  )
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa+        [ check_ims ] Image made : ['tst.psf', 'tst.residual', 'tst.mask', 'tst.model'] =  [True, True, True, True] ( Pass : should all be True )
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa+        [check_history] All expected history entries found. (Pass)
02-Jul-2026 21:04:22 2026-07-02 21:04:19        INFO        deconvolve::::casa+        [ check_ims ] Image made : ['tst.image'] =  [False] ( Pass : should all be False )
02-Jul-2026 21:04:22 .2026-07-02 21:04:20        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:04:22 2026-07-02 21:04:20        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:04:22 2026-07-02 21:04:20        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=False, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : hogbom
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        task_deconvolve::SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1.0601
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.0601, over full image : 1.0601
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SDAlgorithmBase::deconvolve         [tst] Run Hogbom minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.727428, peakres=1.0601->0.33267, Reached cycleniter.
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.33267, over full image : 0.33267
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.residual', 'tst.psf', 'tst.pb', 'tst.mask', 'tst.model']
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:04:21.778571 End time: 2026-07-02 17:04:22.111163
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        name::        No table opened.
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        showcache::        The Table Cache is empty.
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : hogbom
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        task_deconvolve::SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.33267, over full image : 0.33267
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing to existing mask
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 100 out of a total of 100 pixels. [ 100 % ]
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.33267, over full image : 0.33267
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        SIImageStore::restore         Beam for chan : 0 : 51.8097 arcsec, 48.6437 arcsec, -88.2966 deg
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.residual', 'tst.psf', 'tst.pb', 'tst.mask', 'tst.model', 'tst.image']
02-Jul-2026 21:04:22 2026-07-02 21:04:22        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:04:22.136662 End time: 2026-07-02 17:04:22.462363
02-Jul-2026 21:04:30 2026-07-02 21:04:22        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:04:30 2026-07-02 21:04:22        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:30 2026-07-02 21:04:22        INFO        name::        No table opened.
02-Jul-2026 21:04:30 2026-07-02 21:04:22        INFO        showcache::        The Table Cache is empty.
02-Jul-2026 21:04:30 2026-07-02 21:04:22        INFO        deconvolve::::casa        [ check_ret_structure ] Return dictionary struture check: type=reduced ( Pass : found all expected keys  : Extra key(s):['stopDescription'] )
02-Jul-2026 21:04:30 2026-07-02 21:04:22        INFO        deconvolve::::casa+        [ check_ims ] Image made : ['tst.psf', 'tst.residual', 'tst.mask', 'tst.model', 'tst.image'] =  [True, True, True, True, True] ( Pass : should all be True )
02-Jul-2026 21:04:30 2026-07-02 21:04:22        INFO        deconvolve::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
02-Jul-2026 21:04:30 2026-07-02 21:04:22        INFO        deconvolve::::casa+        [check_history] All expected history entries found. (Pass)
02-Jul-2026 21:04:30 2026-07-02 21:04:22        INFO        deconvolve::::casa+        [ check_pix_val ] tst.model : Value is 0.7274277806282043 at [5, 5, 0, 0] ( Pass : should be 0.727 , Epsilon: 0.05)
02-Jul-2026 21:04:30 .2026-07-02 21:04:23        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:04:30 2026-07-02 21:04:23        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:04:30 2026-07-02 21:04:23        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:04:30 2026-07-02 21:04:25        INFO        deconvolve::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:04:30 /export/cvpost134-4tb/casa/casa-data/casatestdata/unittest/deconvolve/
02-Jul-2026 21:04:30 refim_point_linRL
02-Jul-2026 21:04:30 2026-07-02 21:04:25        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:04:30 2026-07-02 21:04:25        INFO        tclean::::casa        ##### Begin Task: tclean             #####
02-Jul-2026 21:04:30 2026-07-02 21:04:25        INFO        tclean::::casa        tclean( vis='refim_point_linRL.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=100, cell='8.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='standard', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=0.2, normtype='flatnoise', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=False, restoringbeam=[], pbcor=True, outlierfile='', weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
02-Jul-2026 21:04:30 2026-07-02 21:04:25        INFO        tclean::::casa        Verifying Input Parameters
02-Jul-2026 21:04:30 2026-07-02 21:04:26        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:04:30 2026-07-02 21:04:26        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:04:30 2026-07-02 21:04:26        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisUtilMethods::continuumDataPartition         
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 95 140 [P][M]: 0:2 ROWNUMBER() >= 95 && ROWNUMBER() <= 140
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_linRL.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 47', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_linRL.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 48 && ROWNUMBER() <= 94', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_linRL.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 95 && ROWNUMBER() <= 140', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisImagerVi2::selectData         MS : refim_point_linRL.ms | [Opened in readonly mode]
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisImagerVi2::selectData           NRows selected : 140
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisImagerVi2::defineImage         Impars: start
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisImagerVi2::defineImage         Shape: [100, 100, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : gridft
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.002124 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:04:30 2026-07-02 21:04:28        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:30 2026-07-02 21:04:29        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:30 2026-07-02 21:04:29        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:30 2026-07-02 21:04:29        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_point_linRL.ms | [Opened in readonly mode]
02-Jul-2026 21:04:30 2026-07-02 21:04:29        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 95 && ROWNUMBER() <= 140 |   NRows selected : 46
02-Jul-2026 21:04:30 2026-07-02 21:04:29        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_point_linRL.ms | [Opened in readonly mode]
02-Jul-2026 21:04:30 2026-07-02 21:04:29        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_point_linRL.ms | [Opened in readonly mode]
02-Jul-2026 21:04:30 2026-07-02 21:04:29        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 47 |   NRows selected : 47
02-Jul-2026 21:04:30 2026-07-02 21:04:29        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 48 && ROWNUMBER() <= 94 |   NRows selected : 47
02-Jul-2026 21:04:30 2026-07-02 21:04:30        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:30 2026-07-02 21:04:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] :
02-Jul-2026 21:04:39 2026-07-02 21:04:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] :
02-Jul-2026 21:04:39 2026-07-02 21:04:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
02-Jul-2026 21:04:39 2026-07-02 21:04:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [100, 100, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:04:39 2026-07-02 21:04:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] with ftmachine : gridft
02-Jul-2026 21:04:39 2026-07-02 21:04:30        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 0.002124 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] :
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] :
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [100, 100, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] with ftmachine : gridft
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 0.002124 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [100, 100, 1, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] with ftmachine : gridft
02-Jul-2026 21:04:39 2026-07-02 21:04:31        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 0.002124 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:04:39 2026-07-02 21:04:32        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:39 2026-07-02 21:04:35        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:39 2026-07-02 21:04:35        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
02-Jul-2026 21:04:39 2026-07-02 21:04:35        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
02-Jul-2026 21:04:39 2026-07-02 21:04:35        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
02-Jul-2026 21:04:39 2026-07-02 21:04:36        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:39 2026-07-02 21:04:36        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:39 2026-07-02 21:04:37        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:39 2026-07-02 21:04:37        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:39 2026-07-02 21:04:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:04:39 2026-07-02 21:04:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:04:39 2026-07-02 21:04:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:04:39 2026-07-02 21:04:38        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:39 2026-07-02 21:04:38        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:04:39 2026-07-02 21:04:38        INFO        SIImageStore::calcSensitivity         [tst] Theoretical sensitivity (Jy/bm):0.0345033
02-Jul-2026 21:04:39 2026-07-02 21:04:38        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.003471
02-Jul-2026 21:04:39 2026-07-02 21:04:38        INFO        SIImageStore::printBeamSet         Beam : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:04:39 2026-07-02 21:04:38        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:39 2026-07-02 21:04:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::makePrimaryBeam::MPIServer-1         vi2 : Evaluating Primary Beam model onto image grid(s)
02-Jul-2026 21:04:39 2026-07-02 21:04:39        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:39 2026-07-02 21:04:39        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3]
02-Jul-2026 21:04:39 2026-07-02 21:04:39        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model does not exist. No initial prediction will be done
02-Jul-2026 21:04:39 2026-07-02 21:04:39        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model does not exist. No initial prediction will be done
02-Jul-2026 21:04:39 2026-07-02 21:04:39        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model does not exist. No initial prediction will be done
02-Jul-2026 21:04:39 2026-07-02 21:04:39        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
02-Jul-2026 21:04:39 2026-07-02 21:04:39        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:51 2026-07-02 21:04:39        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:04:51 2026-07-02 21:04:39        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:04:51 2026-07-02 21:04:39        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:04:51 2026-07-02 21:04:40        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:51 2026-07-02 21:04:40        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:04:51 2026-07-02 21:04:47        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:51 2026-07-02 21:04:47        INFO        ::casa::MPIServer-1        node 1 has completed its job
02-Jul-2026 21:04:51 2026-07-02 21:04:47        INFO        ::casa::MPIServer-2        node 2 has completed its job
02-Jul-2026 21:04:51 2026-07-02 21:04:47        INFO        ::casa::MPIServer-3        node 3 has completed its job
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        tclean::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf', 'tst.sumwt', 'tst.pb', 'tst.residual']
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-02 17:04:24.920873 End time: 2026-07-02 17:04:48.026084
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        tclean::::casa        ##### End Task: tclean               #####
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='hogbom', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : hogbom
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 10000 out of a total of 10000 pixels. [ 100 % ]
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1, over full image : 1
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        SDAlgorithmBase::deconvolve         [tst] Run Hogbom minor-cycle | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.68619, peakres=1->0.313811, Reached cycleniter.
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.313811, over full image : 0.313811
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        SIImageStore::restore         Beam for chan : 0 : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:04:51 2026-07-02 21:04:48        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf', 'tst.sumwt', 'tst.pb', 'tst.residual', 'tst.mask', 'tst.model', 'tst.image']
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:04:48.027949 End time: 2026-07-02 17:04:48.545560
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        name::        No table opened.
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        showcache::        The Table Cache is empty.
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        deconvolve::::casa        [ checkall ]
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        deconvolve::::casa+        [ check_ims ] Image made : ['tst.image'] =  [True] ( Pass : should all be True )
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        deconvolve::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        deconvolve::::casa+        [check_history] All expected history entries found. (Pass)
02-Jul-2026 21:04:51 2026-07-02 21:04:49        INFO        deconvolve::::casa+        [ check_pix_val ] tst.image : Value is 1.0000007152557373 at [50, 50, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
02-Jul-2026 21:04:51 .2026-07-02 21:04:50        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:04:51 2026-07-02 21:04:50        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:04:51 2026-07-02 21:04:50        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:04:51 2026-07-02 21:04:52        INFO        deconvolve::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:04:51 /export/cvpost134-4tb/casa/casa-data/casatestdata/unittest/deconvolve/
02-Jul-2026 21:04:51 refim_point_linRL
02-Jul-2026 21:04:51 2026-07-02 21:04:52        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:04:51 2026-07-02 21:04:52        INFO        tclean::::casa        ##### Begin Task: tclean             #####
02-Jul-2026 21:04:51 2026-07-02 21:04:52        INFO        tclean::::casa        tclean( vis='refim_point_linRL.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=100, cell='8.0arcsec', phasecenter='', stokes='IQUV', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='standard', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=0.2, normtype='flatnoise', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=False, restoringbeam=[], pbcor=True, outlierfile='', weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=0, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
02-Jul-2026 21:04:58 2026-07-02 21:04:52        WARN        tclean::task_tclean::casa        For specmode='mfs' and deconvolver='mtmfs', the option of pbcor=True divides each restored Taylor coefficient image by the pb.tt0 image. This correction ignores the frequency-dependence of the primary beam and does not correct for PB spectral index. It is scientifically valid only for small fractional bandwidths. For more accurate wideband primary beam correction (if needed), please use one of the following options : (1) specmode='mvc' with gridder='standard' or 'mosaic' with pbcor=True,  (2) conjbeams=True and wbawp=True with gridder='awproject' and pbcor=True.
02-Jul-2026 21:04:58 2026-07-02 21:04:52        INFO        tclean::::casa        Verifying Input Parameters
02-Jul-2026 21:04:58 2026-07-02 21:04:53        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
02-Jul-2026 21:04:58 2026-07-02 21:04:53        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
02-Jul-2026 21:04:58 2026-07-02 21:04:53        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisUtilMethods::continuumDataPartition         
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 95 140 [P][M]: 0:2 ROWNUMBER() >= 95 && ROWNUMBER() <= 140
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_linRL.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 47', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_linRL.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 48 && ROWNUMBER() <= 94', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_point_linRL.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 95 && ROWNUMBER() <= 140', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisImagerVi2::selectData         MS : refim_point_linRL.ms | [Opened in readonly mode]
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisImagerVi2::selectData           NRows selected : 140
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisImagerVi2::defineImage         Impars: start
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisImagerVi2::defineImage         Shape: [100, 100, 4, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : gridft
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.01416 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:04:58 2026-07-02 21:04:55        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:58 2026-07-02 21:04:56        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:58 2026-07-02 21:04:56        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:58 2026-07-02 21:04:56        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_point_linRL.ms | [Opened in readonly mode]
02-Jul-2026 21:04:58 2026-07-02 21:04:56        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 47 |   NRows selected : 47
02-Jul-2026 21:04:58 2026-07-02 21:04:56        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_point_linRL.ms | [Opened in readonly mode]
02-Jul-2026 21:04:58 2026-07-02 21:04:56        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 48 && ROWNUMBER() <= 94 |   NRows selected : 47
02-Jul-2026 21:04:58 2026-07-02 21:04:56        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_point_linRL.ms | [Opened in readonly mode]
02-Jul-2026 21:04:58 2026-07-02 21:04:56        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 95 && ROWNUMBER() <= 140 |   NRows selected : 46
02-Jul-2026 21:04:58 2026-07-02 21:04:57        INFO        tclean::::casa        ...done
02-Jul-2026 21:04:58 2026-07-02 21:04:57        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] :
02-Jul-2026 21:04:58 2026-07-02 21:04:57        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] :
02-Jul-2026 21:04:58 2026-07-02 21:04:57        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
02-Jul-2026 21:04:58 2026-07-02 21:04:57        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [100, 100, 4, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:04:58 2026-07-02 21:04:57        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1] with ftmachine : gridft
02-Jul-2026 21:04:58 2026-07-02 21:04:57        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 0.01416 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:04:58 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] :
02-Jul-2026 21:04:58 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] :
02-Jul-2026 21:04:58 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
02-Jul-2026 21:04:58 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [100, 100, 4, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:04:58 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2] with ftmachine : gridft
02-Jul-2026 21:04:58 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 0.01416 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:04:58 2026-07-02 21:04:58        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:04:58 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:04:58 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] :
02-Jul-2026 21:05:21 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
02-Jul-2026 21:05:21 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [100, 100, 4, 1] Spectral: [1.49998e+09] at [0] with increment [1.49999e+09]
02-Jul-2026 21:05:21 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] with ftmachine : gridft
02-Jul-2026 21:05:21 2026-07-02 21:04:58        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 0.01416 GB. Available mem.: 391 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
02-Jul-2026 21:05:21 2026-07-02 21:04:59        INFO        tclean::::casa        ...done
02-Jul-2026 21:05:21 2026-07-02 21:05:01        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:05:21 2026-07-02 21:05:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
02-Jul-2026 21:05:21 2026-07-02 21:05:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
02-Jul-2026 21:05:21 2026-07-02 21:05:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
02-Jul-2026 21:05:21 2026-07-02 21:05:02        INFO        tclean::::casa        ...done
02-Jul-2026 21:05:21 2026-07-02 21:05:02        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:05:21 2026-07-02 21:05:03        INFO        tclean::::casa        ...done
02-Jul-2026 21:05:21 2026-07-02 21:05:03        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:05:21 2026-07-02 21:05:04        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:05:21 2026-07-02 21:05:04        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:05:21 2026-07-02 21:05:04        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
02-Jul-2026 21:05:21 2026-07-02 21:05:05        INFO        tclean::::casa        ...done
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStoreMultiTerm::calcSensitivity +        [420, -0.000161385
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStoreMultiTerm::calcSensitivity +         -0.000161385, 31.1111]
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStoreMultiTerm::calcSensitivity +        [0.00238095, 1.23509e-08
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStoreMultiTerm::calcSensitivity +         1.23509e-08, 0.0321429]
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor0] Theoretical sensitivity (Jy/bm):0.048795
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor1] Theoretical sensitivity (Jy/bm):0.179284
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749992 GHz -> 2.24998GHz.Fractional Bandwidth : 100 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.012135
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStore::printBeamSet         Restoring Beams
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStore::printBeamSet +        Chan    Freq   Vel Pol
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStore::printBeamSet +           0 1.5e+09 -149892   I  137.5722 arcsec x   47.9881 arcsec pa= 25.3711 deg
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStore::printBeamSet +           0 1.5e+09 -149892   Q  137.5722 arcsec x   47.9881 arcsec pa= 25.3711 deg
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStore::printBeamSet +           0 1.5e+09 -149892   U  137.5722 arcsec x   47.9881 arcsec pa= 25.3711 deg
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        SIImageStore::printBeamSet +           0 1.5e+09 -149892   V  137.5722 arcsec x   47.9881 arcsec pa= 25.3711 deg
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:05:21 2026-07-02 21:05:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::makePrimaryBeam::MPIServer-1         vi2 : Evaluating Primary Beam model onto image grid(s)
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        tclean::::casa        ...done
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3]
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt0 does not exist. No initial prediction will be done for term0
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt1 does not exist. No initial prediction will be done for term1
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt0 does not exist. No initial prediction will be done for term0
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt1 does not exist. No initial prediction will be done for term1
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt0 does not exist. No initial prediction will be done for term0
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        task_tclean::SIImageStore::setModelImageOne         Starting model image tst.model.tt1 does not exist. No initial prediction will be done for term1
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:05:21 2026-07-02 21:05:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:05:21 2026-07-02 21:05:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
02-Jul-2026 21:05:21 2026-07-02 21:05:08        INFO        tclean::::casa        ...done
02-Jul-2026 21:05:21 2026-07-02 21:05:08        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n1, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n2, /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.workdirectory/tst.n3] onto :tst
02-Jul-2026 21:05:23 2026-07-02 21:05:21        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
02-Jul-2026 21:05:23 2026-07-02 21:05:21        INFO        ::casa::MPIServer-2        node 2 has completed its job
02-Jul-2026 21:05:23 2026-07-02 21:05:21        INFO        ::casa::MPIServer-1        node 1 has completed its job
02-Jul-2026 21:05:23 2026-07-02 21:05:21        INFO        ::casa::MPIServer-3        node 3 has completed its job
02-Jul-2026 21:05:23 2026-07-02 21:05:22        INFO        tclean::::casa        ...done
02-Jul-2026 21:05:23 2026-07-02 21:05:22        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
02-Jul-2026 21:05:23 2026-07-02 21:05:22        INFO        tclean::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf.tt0', 'tst.sumwt.tt0', 'tst.psf.tt1', 'tst.sumwt.tt1', 'tst.psf.tt2', 'tst.sumwt.tt2', 'tst.pb.tt0', 'tst.residual.tt0', 'tst.residual.tt1']
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        tclean::::casa        Task tclean complete. Start time: 2026-07-02 17:04:51.866219 End time: 2026-07-02 17:05:22.607625
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        tclean::::casa        ##### End Task: tclean               #####
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        tclean::::casa        ##########################################
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        deconvolve::::casa        ##### Begin Task: deconvolve         #####
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        deconvolve::::casa        deconvolve( imagename='tst', startmodel='', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], niter=10, gain=0.1, threshold=0.0, nsigma=0.0, interactive=False, fullsummary=False, fastnoise=True, usemask='user', mask='', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, verbose=False )
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        deconvolve::::casa        Verifying Input Parameters
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : mtmfs
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 4
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 40000 out of a total of 40000 pixels. [ 100 % ]
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 4, over full image : 4
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SDAlgorithmBase::deconvolve         [tst] Run mtmfs minor-cycle on 4 pols | CycleThreshold=0, CycleNiter=10, Gain=0.1
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 80 pixels on each side for minor-cycle updates.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [50, 50]
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749992 GHz -> 2.24998GHz.Fractional Bandwidth : 100 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [1, -3.82996e-07
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -3.82996e-07, 0.074074]
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 1
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO                [10] Res: 0.348679 Max: 0.150095 Gain: 0.1 Pos: [50, 50] Scale: 0 Coeffs: 0.387421  -7.18246e-08 
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0.651322 (in this run)
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 0.651322  [1]: -1.20749e-07
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SDAlgorithmBase::deconvolve         [tst:P0] iters=0->10 [10], model=0->0.651322, peakres=1->0.348679, Reached cycleniter.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749992 GHz -> 2.24998GHz.Fractional Bandwidth : 100 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 2.00049
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO                [20] Res: 0.697528 Max: 0.600675 Gain: 0.1 Pos: [50, 50] Scale: 0 Coeffs: 0.775031  -0.00584575 
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 1.95428 (in this run)
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 1.30296  [1]: -0.00982773
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SDAlgorithmBase::deconvolve         [tst:P1] iters=10->20 [10], model=0->1.30296, peakres=2.00049->0.697527, Reached cycleniter.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749992 GHz -> 2.24998GHz.Fractional Bandwidth : 100 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 2.99594
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 3.90561 (in this run)
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 1.95132  [1]: 0.0150923
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SDAlgorithmBase::deconvolve         [tst:P2] iters=20->30 [10], model=0->1.95132, peakres=2.99594->1.04462, Reached cycleniter.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749992 GHz -> 2.24998GHz.Fractional Bandwidth : 100 %.Reference Frequency for Taylor Expansion : 1.49998GHz.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 4
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO                [40] Res: 1.39471 Max: 2.40152 Gain: 0.1 Pos: [50, 50] Scale: 0 Coeffs: 1.54968  -9.57616e-08 
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 6.5109 (in this run)
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 2.60529  [1]: -1.60993e-07
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SDAlgorithmBase::deconvolve         [tst:P3] iters=30->40 [10], model=0->2.60529, peakres=4->1.39471, Reached cycleniter.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SDAlgorithmBase::deconvolve         [tst] Total model flux (over all planes) : 6.5109     Peak Residual (over all planes) : 1.39471 in C0:P3
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 40 iterations.
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        task_deconvolve::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.39471, over full image : 1.39471
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        deconvolve::::casa        Reached global stopping criterion : iteration limit
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
02-Jul-2026 21:05:23 2026-07-02 21:05:23        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SIImageStore::restore         Beam for chan : 0 : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SIImageStore::restore         Beam for chan : 0 : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Alpha and Beta images will not be computed for images that contain anything other than stokes I in them.
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SIImageStore::restore         Beam for chan : 0 : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SIImageStore::restore         Beam for chan : 0 : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Alpha and Beta images will not be computed for images that contain anything other than stokes I in them.
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SIImageStore::restore         Beam for chan : 0 : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SIImageStore::restore         Beam for chan : 0 : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:05:41 2026-07-02 21:05:23        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Alpha and Beta images will not be computed for images that contain anything other than stokes I in them.
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        SIImageStore::restore         Beam for chan : 0 : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        SIImageStore::restore         Beam for chan : 0 : 137.572 arcsec, 47.9881 arcsec, 25.3711 deg
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Alpha and Beta images will not be computed for images that contain anything other than stokes I in them.
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        SDAlgorithmMSMFS::restore         Restored images :
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        SDAlgorithmMSMFS::restore         /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.image.tt0  (model=/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.model.tt0)
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        SDAlgorithmMSMFS::restore         /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.image.tt1  (model=/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228/work/test_task_deconvolve/tst.model.tt1)
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        SDAlgorithmMSMFS::restore         
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa        Searching for images with prefix 'tst'... Found these, writing history into them: ['tst.psf.tt0', 'tst.sumwt.tt0', 'tst.psf.tt1', 'tst.sumwt.tt1', 'tst.psf.tt2', 'tst.sumwt.tt2', 'tst.pb.tt0', 'tst.residual.tt0', 'tst.residual.tt1', 'tst.mask', 'tst.model.tt0', 'tst.model.tt1', 'tst.image.tt0', 'tst.image.tt1']
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa        Task deconvolve complete. Start time: 2026-07-02 17:05:22.609534 End time: 2026-07-02 17:05:24.022810
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa        ##### End Task: deconvolve           #####
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa        ##########################################
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        name::        No table opened.
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        showcache::        The Table Cache is empty.
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa        [ checkall ]
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa+        [ check_ims ] Image made : ['tst.image.tt0'] =  [True] ( Pass : should all be True )
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa+        [check_history] All expected history entries found. (Pass)
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa+        [ check_ims ] Image made : ['tst.image.alpha'] =  [False] ( Pass : should all be False )
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 1.0000007152557373 at [50, 50, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 2.0004897117614746 at [50, 50, 1, 0] ( Pass : should be 2.0 , Epsilon: 0.05)
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 2.9959442615509033 at [50, 50, 2, 0] ( Pass : should be 3.0 , Epsilon: 0.05)
02-Jul-2026 21:05:41 2026-07-02 21:05:24        INFO        deconvolve::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 4.000002861022949 at [50, 50, 3, 0] ( Pass : should be 4.0 , Epsilon: 0.05)
02-Jul-2026 21:05:41 .
02-Jul-2026 21:05:41 ----------------------------------------------------------------------
02-Jul-2026 21:05:41 Ran 143 tests in 1702.153s
02-Jul-2026 21:05:41
02-Jul-2026 21:05:41 OK (skipped=2)
02-Jul-2026 21:05:41 Marking test as passed.
02-Jul-2026 21:05:41 2026-07-02 21:05:32        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 1
02-Jul-2026 21:05:41 2026-07-02 21:05:32        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 3
02-Jul-2026 21:05:41 2026-07-02 21:05:32        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal self.signal_stop_virtual_frame_buffer() successfully handled by server 2
02-Jul-2026 21:05:41 2026-07-02 21:05:35        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal self.signal_stop_service_request() successfully handled by server 1
02-Jul-2026 21:05:41 2026-07-02 21:05:35        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal self.signal_stop_service_request() successfully handled by server 3
02-Jul-2026 21:05:41 2026-07-02 21:05:35        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal self.signal_stop_service_request() successfully handled by server 2
02-Jul-2026 21:05:41 2026-07-02 21:05:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        stop service signal received, stopping services...
02-Jul-2026 21:05:41 2026-07-02 21:05:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        stop service signal received, stopping services...
02-Jul-2026 21:05:41 2026-07-02 21:05:38        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        stop service signal received, stopping services...
02-Jul-2026 21:05:41 2026-07-02 21:05:41        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-3        MPI command request handler service stopped
02-Jul-2026 21:05:41 2026-07-02 21:05:41        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Going to finalize MPI environment
02-Jul-2026 21:05:41 2026-07-02 21:05:41        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-1        MPI command request handler service stopped
02-Jul-2026 21:05:41 2026-07-02 21:05:41        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Going to finalize MPI environment
02-Jul-2026 21:05:41 2026-07-02 21:05:41        INFO        MPICommandServer::stop_command_request_handler_service::MPICommandServer::stop_command_request_handler_service::casa::MPIServer-2        MPI command request handler service stopped
02-Jul-2026 21:05:41 2026-07-02 21:05:41        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Going to finalize MPI environment
02-Jul-2026 21:05:41 2026-07-02 21:05:41        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Exiting
02-Jul-2026 21:05:41 2026-07-02 21:05:41        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Exiting
02-Jul-2026 21:05:41 2026-07-02 21:05:41        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Exiting
02-Jul-2026 21:05:41 Executor result: 0
02-Jul-2026 21:05:41 Failed tests:[]
02-Jul-2026 21:05:41
02-Jul-2026 21:05:41 Results: [{'testname': 'test_task_deconvolve', 'runtime': '0:28:43.319653', 'returncode': 0, 'testerr': []}]
02-Jul-2026 21:05:41 Stopping Xvfb.
02-Jul-2026 21:05:41 Stopping framebuffer <subprocess.Popen object at 0x7f6c509f12b0>
02-Jul-2026 21:05:41 Virtual frame buffer deployed at :1825183 with pid 1825224 successfully shutdown
02-Jul-2026 21:05:41 Xvfb stopped.
02-Jul-2026 21:05:41 Finished task 'Test' with result: Success
02-Jul-2026 21:05:41 Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
02-Jul-2026 21:05:41 Parsing test results under /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31248-CCM228...
02-Jul-2026 21:05:41 Finished task 'Parse test results' with result: Success
02-Jul-2026 21:05:41 Running post build plugin 'Docker Container Cleanup'
02-Jul-2026 21:05:41 Running post build plugin 'NCover Results Collector'
02-Jul-2026 21:05:42 Running post build plugin 'Build Results Label Collector'
02-Jul-2026 21:05:42 Running post build plugin 'Clover Results Collector'
02-Jul-2026 21:05:42 Running post build plugin 'npm Cache Cleanup'
02-Jul-2026 21:05:42 Running post build plugin 'Artifact Copier'
02-Jul-2026 21:05:42 Finalising the build...
02-Jul-2026 21:05:42 Stopping timer.
02-Jul-2026 21:05:42 Build CASASPECS-DEVVERCASALITHPY31248-CCM228-2 completed.
02-Jul-2026 17:05:42 Running on server: post build plugin 'Build Hanging Detection Configuration'
02-Jul-2026 17:05:42 Running on server: post build plugin 'NCover Results Collector'
02-Jul-2026 17:05:42 Running on server: post build plugin 'Build Labeller'
02-Jul-2026 17:05:42 Running on server: post build plugin 'Clover Delta Calculator'
02-Jul-2026 17:05:42 Running on server: post build plugin 'Maven Dependencies Postprocessor'
02-Jul-2026 17:05:42 All post build plugins have finished
02-Jul-2026 17:05:42 Generating build results summary...
02-Jul-2026 17:05:42 Saving build results to disk...
02-Jul-2026 17:05:42 Store variable context...
02-Jul-2026 17:05:42 Finished building CASASPECS-DEVVERCASALITHPY31248-CCM228-2.