Build: #3 did not complete

Job: Test MPI Task Tclean Many Linux 2.28 Rocky 8.10 Docker did not complete

Build log

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

20-Apr-2026 17:38:13 2026-04-20 17:38:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:38:22 2026-04-20 17:38:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:38:22 2026-04-20 17:38:17        INFO        tclean::::casa        ...done
20-Apr-2026 17:38:22 2026-04-20 17:38:17        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStoreMultiTerm::calcSensitivity +        [25086, -33.0664
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStoreMultiTerm::calcSensitivity +         -33.0664, 667.347]
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStoreMultiTerm::calcSensitivity +        [3.98655e-05, 1.9753e-06
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStoreMultiTerm::calcSensitivity +         1.9753e-06, 0.00149857]
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor0] Theoretical sensitivity (Jy/bm):0.00631391
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor1] Theoretical sensitivity (Jy/bm):0.0387114
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.95001GHz.Fractional Bandwidth : 60.0016 %.Reference Frequency for Taylor Expansion : 1.5GHz.
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.009805
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        SIImageStore::printBeamSet         Beam : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:38:22 2026-04-20 17:38:18        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:38:22 2026-04-20 17:38:19        INFO        tclean::::casa        ...done
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3]
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:38:22 2026-04-20 17:38:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:38:22 2026-04-20 17:38:22        INFO        tclean::::casa        ...done
20-Apr-2026 17:38:22 2026-04-20 17:38:22        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:38:22 2026-04-20 17:38:22        INFO        task_tclean::MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
20-Apr-2026 17:38:22 2026-04-20 17:38:22        INFO        task_tclean::MultiTermMatrixCleaner::initialise()         Using a PSF patch of 80 pixels on each side for minor-cycle updates.
20-Apr-2026 17:38:22 2026-04-20 17:38:22        INFO        task_tclean::MatrixCleaner::validatePsf()         Peak of PSF = 1 at [512, 512]
20-Apr-2026 17:38:22 2026-04-20 17:38:22        INFO        task_tclean::MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
20-Apr-2026 17:38:46 2026-04-20 17:38:22        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak +        [1, -0.00145283
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak +         -0.00145283, 0.026594]
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::SDAlgorithmBase::restore         [tst] : Restoring model image.
20-Apr-2026 17:38:46 2026-04-20 17:38:23        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2352)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:38:46 2026-04-20 17:38:23        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2352)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.053029 Jy/beam
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /home/casatest/work/test_task_tclean/tst.alpha.error
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::SDAlgorithmMSMFS::restore         Restored images :
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt0  (model=/home/casatest/work/test_task_tclean/tst.model.tt0)
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt1  (model=/home/casatest/work/test_task_tclean/tst.model.tt1)
20-Apr-2026 17:38:46 2026-04-20 17:38:23        INFO        task_tclean::SDAlgorithmMSMFS::restore         
20-Apr-2026 17:38:46 2026-04-20 17:38:42        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:38:46 2026-04-20 17:38:42        INFO        ::casa::MPIServer-3        node 3 has completed its job
20-Apr-2026 17:38:46 2026-04-20 17:38:42        INFO        ::casa::MPIServer-2        node 2 has completed its job
20-Apr-2026 17:38:46 2026-04-20 17:38:42        INFO        ::casa::MPIServer-1        node 1 has completed its job
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        tclean::::casa        ...done
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
20-Apr-2026 17:38:46 2026-04-20 17:38:43        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.weight.tt0', 'tst.weight.tt1', 'tst.weight.tt2', 'tst.pb.tt0', 'tst.model.tt0', 'tst.model.tt1', 'tst.residual.tt0', 'tst.residual.tt1', 'tst.image.tt0', 'tst.image.tt1', 'tst.alpha', 'tst.alpha.error']
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        tclean::::casa        Task tclean complete. Start time: 2026-04-20 17:38:01.580436 End time: 2026-04-20 17:38:43.224731
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        tclean::::casa        ##### End Task: tclean               #####
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        name::        No table opened.
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        showcache::        The Table Cache is empty.
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        tclean::::casa        ##### Begin Task: tclean             #####
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        tclean::::casa        tclean( vis='refim_oneshiftpoint.mosaic.ms', selectdata=True, field='0', spw='*', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=1024, cell='10.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='1.5GHz', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='mosaic', 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.1, normtype='flatnoise', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, outlierfile='', weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=10, 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 )
20-Apr-2026 17:38:46 2026-04-20 17:38:43        WARN        tclean::task_tclean::casa        Please consider using specmode=mvc with mosaic gridder as this gridder does not implement conjbeams
20-Apr-2026 17:38:46 2026-04-20 17:38:43        WARN        tclean::task_tclean::casa+         thus it needs a few major cycles to converge towards the correct answer
20-Apr-2026 17:38:46 2026-04-20 17:38:43        INFO        tclean::::casa        Verifying Input Parameters
20-Apr-2026 17:38:46 2026-04-20 17:38:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
20-Apr-2026 17:38:46 2026-04-20 17:38:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
20-Apr-2026 17:38:46 2026-04-20 17:38:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
20-Apr-2026 17:38:46 2026-04-20 17:38:47        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
20-Apr-2026 17:38:46 2026-04-20 17:38:47        INFO        SynthesisUtilMethods::continuumDataPartition         
20-Apr-2026 17:38:46 2026-04-20 17:38:47        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 8427 12636 [P][M]: 0:2 ROWNUMBER() >= 8427 && ROWNUMBER() <= 12636
20-Apr-2026 17:38:46 2026-04-20 17:38:47        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 4213', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 4214 && ROWNUMBER() <= 8426', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 8427 && ROWNUMBER() <= 12636', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
20-Apr-2026 17:38:46 2026-04-20 17:38:47        INFO        SynthesisImagerVi2::selectData         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:38:46 2026-04-20 17:38:47        INFO        SynthesisImagerVi2::selectData           NRows selected : 12636
20-Apr-2026 17:38:46 2026-04-20 17:38:47        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:38:46 2026-04-20 17:38:47        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:38:46 2026-04-20 17:38:47        INFO        SynthesisImagerVi2::defineImage         Impars: start
20-Apr-2026 17:38:55 2026-04-20 17:38:47        INFO        SynthesisImagerVi2::defineImage         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:38:55 2026-04-20 17:38:47        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : mosaicft
20-Apr-2026 17:38:55 2026-04-20 17:38:47        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:38:55 2026-04-20 17:38:47        INFO        VPSkyJones::VPSkyJones        PB used EVLA
20-Apr-2026 17:38:55 2026-04-20 17:38:47        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:38:55 2026-04-20 17:38:47        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:38:55 2026-04-20 17:38:48        INFO        tclean::::casa        ...done
20-Apr-2026 17:38:55 2026-04-20 17:38:48        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:38:55 2026-04-20 17:38:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:38:55 2026-04-20 17:38:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 8427 && ROWNUMBER() <= 12636 |   NRows selected : 4210
20-Apr-2026 17:38:55 2026-04-20 17:38:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:38:55 2026-04-20 17:38:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 4214 && ROWNUMBER() <= 8426 |   NRows selected : 4213
20-Apr-2026 17:38:55 2026-04-20 17:38:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:38:55 2026-04-20 17:38:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 4213 |   NRows selected : 4213
20-Apr-2026 17:38:55 2026-04-20 17:38:49        INFO        tclean::::casa        ...done
20-Apr-2026 17:38:55 2026-04-20 17:38:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:38:55 2026-04-20 17:38:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:38:55 2026-04-20 17:38:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
20-Apr-2026 17:38:55 2026-04-20 17:38:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:38:55 2026-04-20 17:38:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] with ftmachine : mosaicft
20-Apr-2026 17:38:55 2026-04-20 17:38:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-1         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:38:55 2026-04-20 17:38:49        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-1        PB used EVLA
20-Apr-2026 17:38:55 2026-04-20 17:38:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] with ftmachine : mosaicft
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-2         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-2        PB used EVLA
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] with ftmachine : mosaicft
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-3         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-3        PB used EVLA
20-Apr-2026 17:38:55 2026-04-20 17:38:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:38:55 2026-04-20 17:38:51        INFO        tclean::::casa        ...done
20-Apr-2026 17:38:55 2026-04-20 17:38:53        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:38:55 2026-04-20 17:38:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
20-Apr-2026 17:38:55 2026-04-20 17:38:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
20-Apr-2026 17:38:55 2026-04-20 17:38:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
20-Apr-2026 17:38:55 2026-04-20 17:38:54        INFO        tclean::::casa        ...done
20-Apr-2026 17:38:55 2026-04-20 17:38:54        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : mtmfs
20-Apr-2026 17:38:55 2026-04-20 17:38:54        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:38:55 2026-04-20 17:38:55        INFO        tclean::::casa        ...done
20-Apr-2026 17:38:55 2026-04-20 17:38:55        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:38:55 2026-04-20 17:38:55        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:38:56 2026-04-20 17:38:55        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:38:56 2026-04-20 17:38:55        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:38:56 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:04 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:04 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:04 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:04 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:04 2026-04-20 17:38:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:04 2026-04-20 17:38:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:39:04 2026-04-20 17:38:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:39:04 2026-04-20 17:38:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:39:04 2026-04-20 17:38:59        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:04 2026-04-20 17:38:59        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStoreMultiTerm::calcSensitivity +        [25086, -33.0664
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStoreMultiTerm::calcSensitivity +         -33.0664, 667.347]
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStoreMultiTerm::calcSensitivity +        [3.98655e-05, 1.9753e-06
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStoreMultiTerm::calcSensitivity +         1.9753e-06, 0.00149857]
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor0] Theoretical sensitivity (Jy/bm):0.00631391
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor1] Theoretical sensitivity (Jy/bm):0.0387114
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.95001GHz.Fractional Bandwidth : 60.0016 %.Reference Frequency for Taylor Expansion : 1.5GHz.
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        SIImageStore::printBeamSet         Beam : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:39:04 2026-04-20 17:39:00        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3]
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
20-Apr-2026 17:39:04 2026-04-20 17:39:01        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:04 2026-04-20 17:39:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:39:04 2026-04-20 17:39:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:39:04 2026-04-20 17:39:02        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:39:04 2026-04-20 17:39:03        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:04 2026-04-20 17:39:03        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 0.532807
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 1.04858e+06 out of a total of 1.04858e+06 pixels. [ 100 % ]
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.532807, over full image : 0.532807
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 10, 'cyclethreshold': 0.07269319891929626, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        SDAlgorithmBase::deconvolve         [tst] Run mtmfs minor-cycle | CycleThreshold=0.0726932, CycleNiter=10, Gain=0.1
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 80 pixels on each side for minor-cycle updates.
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [512, 512]
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.95001GHz.Fractional Bandwidth : 60.0016 %.Reference Frequency for Taylor Expansion : 1.5GHz.
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [1, -0.00145283
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00145283, 0.026594]
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 0.532807
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO                [10] Res: 0.185778 Max: 0.0548001 Gain: 0.1 Pos: [512, 596] Scale: 0 Coeffs: 0.205437  -0.677079 
20-Apr-2026 17:39:04 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
20-Apr-2026 17:39:31 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0.345375 (in this run)
20-Apr-2026 17:39:31 2026-04-20 17:39:04        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 0.345375  [1]: -1.13829
20-Apr-2026 17:39:31 2026-04-20 17:39:04        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.345375, peakres=0.532807->0.185778, Reached cycleniter.
20-Apr-2026 17:39:31 2026-04-20 17:39:04        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
20-Apr-2026 17:39:31 2026-04-20 17:39:04        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3]
20-Apr-2026 17:39:31 2026-04-20 17:39:04        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
20-Apr-2026 17:39:31 2026-04-20 17:39:04        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
20-Apr-2026 17:39:31 2026-04-20 17:39:04        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
20-Apr-2026 17:39:31 2026-04-20 17:39:05        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
20-Apr-2026 17:39:31 2026-04-20 17:39:05        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
20-Apr-2026 17:39:31 2026-04-20 17:39:05        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
20-Apr-2026 17:39:31 2026-04-20 17:39:05        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
20-Apr-2026 17:39:31 2026-04-20 17:39:05        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:31 2026-04-20 17:39:05        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
20-Apr-2026 17:39:31 2026-04-20 17:39:05        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
20-Apr-2026 17:39:31 2026-04-20 17:39:05        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.104871, over full image : 0.104871
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        tclean::::casa        Reached global stopping criterion : iteration limit
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        tclean::::casa        getSummary call: fullsummary=False
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SIImageStore::restore         Beam for chan : 0 : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SIImageStore::restore         Beam for chan : 0 : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.0105431 Jy/beam
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /home/casatest/work/test_task_tclean/tst.alpha.error
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SDAlgorithmMSMFS::restore         Restored images :
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt0  (model=/home/casatest/work/test_task_tclean/tst.model.tt0)
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt1  (model=/home/casatest/work/test_task_tclean/tst.model.tt1)
20-Apr-2026 17:39:31 2026-04-20 17:39:07        INFO        SDAlgorithmMSMFS::restore         
20-Apr-2026 17:39:31 2026-04-20 17:39:26        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:31 2026-04-20 17:39:26        INFO        ::casa::MPIServer-1        node 1 has completed its job
20-Apr-2026 17:39:31 2026-04-20 17:39:26        INFO        ::casa::MPIServer-2        node 2 has completed its job
20-Apr-2026 17:39:31 2026-04-20 17:39:26        INFO        ::casa::MPIServer-3        node 3 has completed its job
20-Apr-2026 17:39:31 2026-04-20 17:39:27        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:31 2026-04-20 17:39:27        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
20-Apr-2026 17:39:31 2026-04-20 17:39:27        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.weight.tt0', 'tst.weight.tt1', 'tst.weight.tt2', 'tst.pb.tt0', 'tst.model.tt0', 'tst.model.tt1', 'tst.residual.tt0', 'tst.residual.tt1', 'tst.image.tt0', 'tst.image.tt1', 'tst.alpha', 'tst.alpha.error', 'tst.mask']
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa        Task tclean complete. Start time: 2026-04-20 17:38:43.239252 End time: 2026-04-20 17:39:27.705816
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa        ##### End Task: tclean               #####
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        name::        No table opened.
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        showcache::        The Table Cache is empty.
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa        [ checkall ]
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 0.5302895307540894 at [512, 596, 0, 0] ( Pass : should be 0.5302894 , Epsilon: 0.05)
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa+        [ check_pix_val ] tst.pb.tt0 : Value is 0.5144197344779968 at [512, 596, 0, 0] ( Pass : should be 0.5144197 , Epsilon: 0.05)
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa+        [ check_pix_val ] tst.alpha : Value is -3.2958009243011475 at [512, 596, 0, 0] ( Pass : should be -3.2958 , Epsilon: 0.05)
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa+        [ checkall ]
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 0.4508061408996582 at [512, 596, 0, 0] ( Pass : should be 0.450807213783 , Epsilon: 0.05)
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa+        [ check_pix_val ] tst.pb.tt0 : Value is 0.5144197344779968 at [512, 596, 0, 0] ( Pass : should be 0.514419734478 , Epsilon: 0.05)
20-Apr-2026 17:39:31 2026-04-20 17:39:28        INFO        tclean::::casa+        [ check_pix_val ] tst.alpha : Value is -1.6695138216018677 at [512, 596, 0, 0] ( Pass : should be -1.6693893671 , Epsilon: 0.05)
20-Apr-2026 17:39:31 .2026-04-20 17:39:30        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
20-Apr-2026 17:39:31 2026-04-20 17:39:30        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
20-Apr-2026 17:39:31 2026-04-20 17:39:30        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
20-Apr-2026 17:39:31 2026-04-20 17:39:32        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
20-Apr-2026 17:39:31 2026-04-20 17:39:32        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:39:31 2026-04-20 17:39:32        INFO        tclean::::casa        ##### Begin Task: tclean             #####
20-Apr-2026 17:39:37 2026-04-20 17:39:32        INFO        tclean::::casa        tclean( vis='refim_oneshiftpoint.mosaic.ms', selectdata=True, field='0', spw='*', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=1024, cell='10.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='1.5GHz', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='mosaic', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=True, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=0.1, normtype='flatnoise', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, 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 )
20-Apr-2026 17:39:37 2026-04-20 17:39:32        WARN        tclean::task_tclean::casa        Please consider using specmode=mvc with mosaic gridder as this gridder does not implement conjbeams
20-Apr-2026 17:39:37 2026-04-20 17:39:32        WARN        tclean::task_tclean::casa+         thus it needs a few major cycles to converge towards the correct answer
20-Apr-2026 17:39:37 2026-04-20 17:39:32        INFO        tclean::::casa        Verifying Input Parameters
20-Apr-2026 17:39:37 2026-04-20 17:39:33        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
20-Apr-2026 17:39:37 2026-04-20 17:39:33        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
20-Apr-2026 17:39:37 2026-04-20 17:39:33        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisUtilMethods::continuumDataPartition         
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 8427 12636 [P][M]: 0:2 ROWNUMBER() >= 8427 && ROWNUMBER() <= 12636
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 4213', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 4214 && ROWNUMBER() <= 8426', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 8427 && ROWNUMBER() <= 12636', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisImagerVi2::selectData         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisImagerVi2::selectData           NRows selected : 12636
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisImagerVi2::defineImage         Impars: start
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisImagerVi2::defineImage         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : mosaicft
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        VPSkyJones::VPSkyJones        PB used EVLA
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:39:37 2026-04-20 17:39:35        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:37 2026-04-20 17:39:36        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:37 2026-04-20 17:39:36        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:37 2026-04-20 17:39:36        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:39:37 2026-04-20 17:39:36        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 8427 && ROWNUMBER() <= 12636 |   NRows selected : 4210
20-Apr-2026 17:39:37 2026-04-20 17:39:36        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:39:37 2026-04-20 17:39:36        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 4213 |   NRows selected : 4213
20-Apr-2026 17:39:37 2026-04-20 17:39:36        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:39:37 2026-04-20 17:39:36        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 4214 && ROWNUMBER() <= 8426 |   NRows selected : 4213
20-Apr-2026 17:39:37 2026-04-20 17:39:37        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:37 2026-04-20 17:39:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:39:37 2026-04-20 17:39:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:39:37 2026-04-20 17:39:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
20-Apr-2026 17:39:37 2026-04-20 17:39:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:39:37 2026-04-20 17:39:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] with ftmachine : mosaicft
20-Apr-2026 17:39:37 2026-04-20 17:39:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-1         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:39:37 2026-04-20 17:39:37        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-1        PB used EVLA
20-Apr-2026 17:39:43 2026-04-20 17:39:37        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] with ftmachine : mosaicft
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-2         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-2        PB used EVLA
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] with ftmachine : mosaicft
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-3         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-3        PB used EVLA
20-Apr-2026 17:39:43 2026-04-20 17:39:38        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:39:43 2026-04-20 17:39:39        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:43 2026-04-20 17:39:41        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:43 2026-04-20 17:39:41        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
20-Apr-2026 17:39:43 2026-04-20 17:39:41        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
20-Apr-2026 17:39:43 2026-04-20 17:39:41        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
20-Apr-2026 17:39:43 2026-04-20 17:39:42        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:43 2026-04-20 17:39:42        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : mtmfs
20-Apr-2026 17:39:43 2026-04-20 17:39:42        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:43 2026-04-20 17:39:43        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:43 2026-04-20 17:39:43        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:43 2026-04-20 17:39:43        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:39:43 2026-04-20 17:39:43        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:39:43 2026-04-20 17:39:43        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:39:43 2026-04-20 17:39:44        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:39:49 2026-04-20 17:39:44        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:39:49 2026-04-20 17:39:47        INFO        tclean::::casa        ...done
20-Apr-2026 17:39:49 2026-04-20 17:39:47        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStoreMultiTerm::calcSensitivity +        [25086, -33.0664
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStoreMultiTerm::calcSensitivity +         -33.0664, 667.347]
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStoreMultiTerm::calcSensitivity +        [3.98655e-05, 1.9753e-06
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStoreMultiTerm::calcSensitivity +         1.9753e-06, 0.00149857]
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor0] Theoretical sensitivity (Jy/bm):0.00631391
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor1] Theoretical sensitivity (Jy/bm):0.0387114
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.95001GHz.Fractional Bandwidth : 60.0016 %.Reference Frequency for Taylor Expansion : 1.5GHz.
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.009786
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        SIImageStore::printBeamSet         Beam : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:39:49 2026-04-20 17:39:48        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:39:49 2026-04-20 17:39:49        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3]
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:40:13 2026-04-20 17:39:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:40:13 2026-04-20 17:39:52        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:13 2026-04-20 17:39:52        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::MultiTermMatrixCleaner::initialise()         Using a PSF patch of 80 pixels on each side for minor-cycle updates.
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::MatrixCleaner::validatePsf()         Peak of PSF = 1 at [512, 512]
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak +        [1, -0.00145283
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak +         -0.00145283, 0.026594]
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::SDAlgorithmBase::restore         [tst] : Restoring model image.
20-Apr-2026 17:40:13 2026-04-20 17:39:53        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2352)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:40:13 2026-04-20 17:39:53        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2352)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.053029 Jy/beam
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /home/casatest/work/test_task_tclean/tst.alpha.error
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::SDAlgorithmMSMFS::restore         Restored images :
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt0  (model=/home/casatest/work/test_task_tclean/tst.model.tt0)
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt1  (model=/home/casatest/work/test_task_tclean/tst.model.tt1)
20-Apr-2026 17:40:13 2026-04-20 17:39:53        INFO        task_tclean::SDAlgorithmMSMFS::restore         
20-Apr-2026 17:40:13 2026-04-20 17:40:12        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:13 2026-04-20 17:40:12        INFO        ::casa::MPIServer-3        node 3 has completed its job
20-Apr-2026 17:40:13 2026-04-20 17:40:12        INFO        ::casa::MPIServer-1        node 1 has completed its job
20-Apr-2026 17:40:13 2026-04-20 17:40:12        INFO        ::casa::MPIServer-2        node 2 has completed its job
20-Apr-2026 17:40:13 2026-04-20 17:40:13        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:13 2026-04-20 17:40:13        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
20-Apr-2026 17:40:13 2026-04-20 17:40:13        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.weight.tt0', 'tst.weight.tt1', 'tst.weight.tt2', 'tst.pb.tt0', 'tst.model.tt0', 'tst.model.tt1', 'tst.residual.tt0', 'tst.residual.tt1', 'tst.image.tt0', 'tst.image.tt1', 'tst.alpha', 'tst.alpha.error']
20-Apr-2026 17:40:13 2026-04-20 17:40:14        INFO        tclean::::casa        Task tclean complete. Start time: 2026-04-20 17:39:31.554046 End time: 2026-04-20 17:40:13.500505
20-Apr-2026 17:40:13 2026-04-20 17:40:14        INFO        tclean::::casa        ##### End Task: tclean               #####
20-Apr-2026 17:40:13 2026-04-20 17:40:14        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:40:13 2026-04-20 17:40:14        INFO        name::        No table opened.
20-Apr-2026 17:40:13 2026-04-20 17:40:14        INFO        showcache::        The Table Cache is empty.
20-Apr-2026 17:40:13 2026-04-20 17:40:14        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:40:13 2026-04-20 17:40:14        INFO        tclean::::casa        ##### Begin Task: tclean             #####
20-Apr-2026 17:40:19 2026-04-20 17:40:14        INFO        tclean::::casa        tclean( vis='refim_oneshiftpoint.mosaic.ms', selectdata=True, field='0', spw='*', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=1024, cell='10.0arcsec', phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='1.5GHz', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='mosaic', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=True, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=0.1, normtype='flatnoise', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, outlierfile='', weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=10, 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 )
20-Apr-2026 17:40:19 2026-04-20 17:40:14        WARN        tclean::task_tclean::casa        Please consider using specmode=mvc with mosaic gridder as this gridder does not implement conjbeams
20-Apr-2026 17:40:19 2026-04-20 17:40:14        WARN        tclean::task_tclean::casa+         thus it needs a few major cycles to converge towards the correct answer
20-Apr-2026 17:40:19 2026-04-20 17:40:14        INFO        tclean::::casa        Verifying Input Parameters
20-Apr-2026 17:40:19 2026-04-20 17:40:15        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
20-Apr-2026 17:40:19 2026-04-20 17:40:15        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
20-Apr-2026 17:40:19 2026-04-20 17:40:15        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisUtilMethods::continuumDataPartition         
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 8427 12636 [P][M]: 0:2 ROWNUMBER() >= 8427 && ROWNUMBER() <= 12636
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 4213', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 4214 && ROWNUMBER() <= 8426', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 8427 && ROWNUMBER() <= 12636', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisImagerVi2::selectData         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisImagerVi2::selectData           NRows selected : 12636
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisImagerVi2::defineImage         Impars: start
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisImagerVi2::defineImage         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : mosaicft
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        VPSkyJones::VPSkyJones        PB used EVLA
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:40:19 2026-04-20 17:40:17        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:19 2026-04-20 17:40:18        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:19 2026-04-20 17:40:18        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:19 2026-04-20 17:40:18        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:40:19 2026-04-20 17:40:18        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 8427 && ROWNUMBER() <= 12636 |   NRows selected : 4210
20-Apr-2026 17:40:19 2026-04-20 17:40:18        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:40:19 2026-04-20 17:40:18        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:40:19 2026-04-20 17:40:18        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 4213 |   NRows selected : 4213
20-Apr-2026 17:40:19 2026-04-20 17:40:18        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 4214 && ROWNUMBER() <= 8426 |   NRows selected : 4213
20-Apr-2026 17:40:19 2026-04-20 17:40:19        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:19 2026-04-20 17:40:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:40:19 2026-04-20 17:40:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:40:19 2026-04-20 17:40:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
20-Apr-2026 17:40:19 2026-04-20 17:40:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:40:19 2026-04-20 17:40:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] with ftmachine : mosaicft
20-Apr-2026 17:40:19 2026-04-20 17:40:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-1         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:40:19 2026-04-20 17:40:19        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-1        PB used EVLA
20-Apr-2026 17:40:25 2026-04-20 17:40:19        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] with ftmachine : mosaicft
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-2         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-2        PB used EVLA
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00023e+08]
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] with ftmachine : mosaicft
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-3         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-3        PB used EVLA
20-Apr-2026 17:40:25 2026-04-20 17:40:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:40:25 2026-04-20 17:40:21        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:25 2026-04-20 17:40:23        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:25 2026-04-20 17:40:23        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
20-Apr-2026 17:40:25 2026-04-20 17:40:23        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
20-Apr-2026 17:40:25 2026-04-20 17:40:23        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
20-Apr-2026 17:40:25 2026-04-20 17:40:24        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:25 2026-04-20 17:40:24        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : mtmfs
20-Apr-2026 17:40:25 2026-04-20 17:40:24        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:25 2026-04-20 17:40:25        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:25 2026-04-20 17:40:25        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:25 2026-04-20 17:40:25        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:40:25 2026-04-20 17:40:25        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:40:25 2026-04-20 17:40:25        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:40:25 2026-04-20 17:40:26        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:40:30 2026-04-20 17:40:26        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:40:30 2026-04-20 17:40:29        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:30 2026-04-20 17:40:29        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStoreMultiTerm::calcSensitivity +        [25086, -33.0664
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStoreMultiTerm::calcSensitivity +         -33.0664, 667.347]
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStoreMultiTerm::calcSensitivity +        [3.98655e-05, 1.9753e-06
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStoreMultiTerm::calcSensitivity +         1.9753e-06, 0.00149857]
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor0] Theoretical sensitivity (Jy/bm):0.00631391
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor1] Theoretical sensitivity (Jy/bm):0.0387114
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.95001GHz.Fractional Bandwidth : 60.0016 %.Reference Frequency for Taylor Expansion : 1.5GHz.
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        SIImageStore::printBeamSet         Beam : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:40:30 2026-04-20 17:40:30        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:30 2026-04-20 17:40:31        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:36 2026-04-20 17:40:31        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3]
20-Apr-2026 17:40:36 2026-04-20 17:40:31        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
20-Apr-2026 17:40:36 2026-04-20 17:40:31        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
20-Apr-2026 17:40:36 2026-04-20 17:40:31        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
20-Apr-2026 17:40:36 2026-04-20 17:40:31        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
20-Apr-2026 17:40:36 2026-04-20 17:40:31        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
20-Apr-2026 17:40:36 2026-04-20 17:40:31        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
20-Apr-2026 17:40:36 2026-04-20 17:40:31        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
20-Apr-2026 17:40:36 2026-04-20 17:40:31        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:36 2026-04-20 17:40:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:40:36 2026-04-20 17:40:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:40:36 2026-04-20 17:40:32        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:40:36 2026-04-20 17:40:33        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:36 2026-04-20 17:40:33        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 0.532807
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        SynthesisDeconvolver::setupMask         [tst] Initializing new mask to 1.0 for the full image
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        SynthesisDeconvolver::setupMask         [tst] Number of pixels in the clean mask : 1.04858e+06 out of a total of 1.04858e+06 pixels. [ 100 % ]
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.532807, over full image : 0.532807
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 10, 'cyclethreshold': 0.07269319891929626, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        SDAlgorithmBase::deconvolve         [tst] Run mtmfs minor-cycle | CycleThreshold=0.0726932, CycleNiter=10, Gain=0.1
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 80 pixels on each side for minor-cycle updates.
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [512, 512]
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.95001GHz.Fractional Bandwidth : 60.0016 %.Reference Frequency for Taylor Expansion : 1.5GHz.
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [1, -0.00145283
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00145283, 0.026594]
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 0.532807
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO                [10] Res: 0.185778 Max: 0.0548001 Gain: 0.1 Pos: [512, 596] Scale: 0 Coeffs: 0.205437  -0.677079 
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0.345375 (in this run)
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 0.345375  [1]: -1.13829
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        SDAlgorithmBase::deconvolve         [tst] iters=0->10 [10], model=0->0.345375, peakres=0.532807->0.185778, Reached cycleniter.
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 10 iterations.
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        task_tclean::SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3]
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
20-Apr-2026 17:40:36 2026-04-20 17:40:34        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
20-Apr-2026 17:40:36 2026-04-20 17:40:35        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
20-Apr-2026 17:40:36 2026-04-20 17:40:35        INFO        task_tclean::SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
20-Apr-2026 17:40:36 2026-04-20 17:40:35        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
20-Apr-2026 17:40:36 2026-04-20 17:40:35        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:40:36 2026-04-20 17:40:35        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
20-Apr-2026 17:40:36 2026-04-20 17:40:35        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
20-Apr-2026 17:40:36 2026-04-20 17:40:35        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
20-Apr-2026 17:40:36 2026-04-20 17:40:37        INFO        tclean::::casa        ...done
20-Apr-2026 17:40:36 2026-04-20 17:40:37        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:40:36 2026-04-20 17:40:37        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.104871, over full image : 0.104871
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        tclean::::casa        Reached global stopping criterion : iteration limit
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        tclean::::casa        getSummary call: fullsummary=False
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        SDAlgorithmBase::restore         [tst] : Restoring model image.
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        SIImageStore::restore         Beam for chan : 0 : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        SIImageStore::restore         Beam for chan : 0 : 27.091 arcsec, 24.2262 arcsec, 62.4282 deg
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.0105431 Jy/beam
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /home/casatest/work/test_task_tclean/tst.alpha.error
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        SDAlgorithmMSMFS::restore         Restored images :
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt0  (model=/home/casatest/work/test_task_tclean/tst.model.tt0)
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt1  (model=/home/casatest/work/test_task_tclean/tst.model.tt1)
20-Apr-2026 17:41:04 2026-04-20 17:40:37        INFO        SDAlgorithmMSMFS::restore         
20-Apr-2026 17:41:04 2026-04-20 17:40:56        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:04 2026-04-20 17:40:56        INFO        ::casa::MPIServer-1        node 1 has completed its job
20-Apr-2026 17:41:04 2026-04-20 17:40:56        INFO        ::casa::MPIServer-2        node 2 has completed its job
20-Apr-2026 17:41:04 2026-04-20 17:40:56        INFO        ::casa::MPIServer-3        node 3 has completed its job
20-Apr-2026 17:41:04 2026-04-20 17:40:57        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:04 2026-04-20 17:40:57        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
20-Apr-2026 17:41:04 2026-04-20 17:40:57        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.weight.tt0', 'tst.weight.tt1', 'tst.weight.tt2', 'tst.pb.tt0', 'tst.model.tt0', 'tst.model.tt1', 'tst.residual.tt0', 'tst.residual.tt1', 'tst.image.tt0', 'tst.image.tt1', 'tst.alpha', 'tst.alpha.error', 'tst.mask']
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa        Task tclean complete. Start time: 2026-04-20 17:40:13.515213 End time: 2026-04-20 17:40:57.691540
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa        ##### End Task: tclean               #####
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        name::        No table opened.
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        showcache::        The Table Cache is empty.
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa        [ checkall ]
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 0.5302895307540894 at [512, 596, 0, 0] ( Pass : should be 0.5302894 , Epsilon: 0.05)
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa+        [ check_pix_val ] tst.pb.tt0 : Value is 0.5144197344779968 at [512, 596, 0, 0] ( Pass : should be 0.5144197 , Epsilon: 0.05)
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa+        [ check_pix_val ] tst.alpha : Value is -3.2958009243011475 at [512, 596, 0, 0] ( Pass : should be -3.2958 , Epsilon: 0.05)
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa+        [ checkall ]
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa+        [ check_pix_val ] tst.image.tt0 : Value is 0.4508061408996582 at [512, 596, 0, 0] ( Pass : should be 0.450807213783 , Epsilon: 0.05)
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa+        [ check_pix_val ] tst.pb.tt0 : Value is 0.5144197344779968 at [512, 596, 0, 0] ( Pass : should be 0.514419734478 , Epsilon: 0.05)
20-Apr-2026 17:41:04 2026-04-20 17:40:58        INFO        tclean::::casa+        [ check_pix_val ] tst.alpha : Value is -1.6695138216018677 at [512, 596, 0, 0] ( Pass : should be -1.6693893671 , Epsilon: 0.05)
20-Apr-2026 17:41:04 .2026-04-20 17:41:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
20-Apr-2026 17:41:04 2026-04-20 17:41:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
20-Apr-2026 17:41:04 2026-04-20 17:41:00        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
20-Apr-2026 17:41:04 2026-04-20 17:41:02        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
20-Apr-2026 17:41:04 2026-04-20 17:41:02        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:41:04 2026-04-20 17:41:02        INFO        tclean::::casa        ##### Begin Task: tclean             #####
20-Apr-2026 17:41:04 2026-04-20 17:41:02        INFO        tclean::::casa        tclean( vis='refim_oneshiftpoint.mosaic.ms', selectdata=True, field='0,1', spw='*', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=1024, cell='10.0arcsec', phasecenter='J2000 19h59m28.5 +40d40m01.5', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='1.5GHz', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='mosaic', 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.1, normtype='flatnoise', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, 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 )
20-Apr-2026 17:41:04 2026-04-20 17:41:02        WARN        tclean::task_tclean::casa        Please consider using specmode=mvc with mosaic gridder as this gridder does not implement conjbeams
20-Apr-2026 17:41:04 2026-04-20 17:41:02        WARN        tclean::task_tclean::casa+         thus it needs a few major cycles to converge towards the correct answer
20-Apr-2026 17:41:04 2026-04-20 17:41:02        INFO        tclean::::casa        Verifying Input Parameters
20-Apr-2026 17:41:04 2026-04-20 17:41:03        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
20-Apr-2026 17:41:04 2026-04-20 17:41:03        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
20-Apr-2026 17:41:04 2026-04-20 17:41:03        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
20-Apr-2026 17:41:04 2026-04-20 17:41:05        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
20-Apr-2026 17:41:04 2026-04-20 17:41:05        INFO        SynthesisUtilMethods::continuumDataPartition         
20-Apr-2026 17:41:04 2026-04-20 17:41:05        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 16851 25272 [P][M]: 0:2 ROWNUMBER() >= 16851 && ROWNUMBER() <= 25272
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0,1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 8425', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0,1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 8426 && ROWNUMBER() <= 16850', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0,1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 16851 && ROWNUMBER() <= 25272', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        SynthesisImagerVi2::selectData         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0,1 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        SynthesisImagerVi2::selectData           NRows selected : 25272
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        SynthesisImagerVi2::defineImage         Impars: start
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        SynthesisImagerVi2::defineImage         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00024e+08]
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : mosaicft
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        VPSkyJones::VPSkyJones        PB used EVLA
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:41:07 2026-04-20 17:41:05        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:07 2026-04-20 17:41:06        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:07 2026-04-20 17:41:06        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:07 2026-04-20 17:41:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0,1 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:41:07 2026-04-20 17:41:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0,1 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:41:07 2026-04-20 17:41:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 8425 |   NRows selected : 8425
20-Apr-2026 17:41:07 2026-04-20 17:41:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 8426 && ROWNUMBER() <= 16850 |   NRows selected : 8425
20-Apr-2026 17:41:07 2026-04-20 17:41:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0,1 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:41:07 2026-04-20 17:41:06        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 16851 && ROWNUMBER() <= 25272 |   NRows selected : 8422
20-Apr-2026 17:41:07 2026-04-20 17:41:07        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:07 2026-04-20 17:41:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:41:07 2026-04-20 17:41:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:41:07 2026-04-20 17:41:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
20-Apr-2026 17:41:07 2026-04-20 17:41:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00024e+08]
20-Apr-2026 17:41:07 2026-04-20 17:41:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] with ftmachine : mosaicft
20-Apr-2026 17:41:07 2026-04-20 17:41:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-1         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:41:07 2026-04-20 17:41:07        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-1        PB used EVLA
20-Apr-2026 17:41:07 2026-04-20 17:41:07        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00024e+08]
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] with ftmachine : mosaicft
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-2         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-2        PB used EVLA
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00024e+08]
20-Apr-2026 17:41:07 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] with ftmachine : mosaicft
20-Apr-2026 17:41:14 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-3         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:41:14 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-3        PB used EVLA
20-Apr-2026 17:41:14 2026-04-20 17:41:08        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:41:14 2026-04-20 17:41:09        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:14 2026-04-20 17:41:11        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:14 2026-04-20 17:41:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
20-Apr-2026 17:41:14 2026-04-20 17:41:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
20-Apr-2026 17:41:14 2026-04-20 17:41:11        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
20-Apr-2026 17:41:14 2026-04-20 17:41:12        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:14 2026-04-20 17:41:12        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : mtmfs
20-Apr-2026 17:41:14 2026-04-20 17:41:12        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:14 2026-04-20 17:41:13        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:14 2026-04-20 17:41:13        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:14 2026-04-20 17:41:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:41:14 2026-04-20 17:41:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:41:14 2026-04-20 17:41:13        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:41:14 2026-04-20 17:41:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:41:22 2026-04-20 17:41:14        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:41:22 2026-04-20 17:41:15        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [12]ELKEY 0_2_1 actualConvInd 2 pointer 1
20-Apr-2026 17:41:22 2026-04-20 17:41:17        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:22 2026-04-20 17:41:17        INFO        SynthesisNormalizer::gatherImages         Gather psf,weight images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStoreMultiTerm::calcSensitivity +        [50174, -66.139
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStoreMultiTerm::calcSensitivity +         -66.139, 1334.69]
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStoreMultiTerm::calcSensitivity +        [1.99319e-05, 9.87702e-07
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStoreMultiTerm::calcSensitivity +         9.87702e-07, 0.000749285]
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor0] Theoretical sensitivity (Jy/bm):0.00446452
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst][Taylor1] Theoretical sensitivity (Jy/bm):0.0273731
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 1.04999 GHz -> 1.95001GHz.Fractional Bandwidth : 60.0016 %.Reference Frequency for Taylor Expansion : 1.5GHz.
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.010047
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        SIImageStore::printBeamSet         Beam : 27.444 arcsec, 24.7765 arcsec, 60.5591 deg
20-Apr-2026 17:41:22 2026-04-20 17:41:18        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:22 2026-04-20 17:41:19        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        SynthesisNormalizer::scatterModel         Send the model from : tst to all nodes :[/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3]
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt0
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1.model.tt1
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt0
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2.model.tt1
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt0 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt0
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        SIImageStore::setModelImageOne         Copying input model tst.model.tt1 to /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3.model.tt1
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        tclean::::casa        -----------------------------  Running Parallel Major Cycle ----------------------------
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-3         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-1         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:41:22 2026-04-20 17:41:20        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::executeMajorCycle::MPIServer-2         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
20-Apr-2026 17:41:22 2026-04-20 17:41:22        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:22 2026-04-20 17:41:22        INFO        SynthesisNormalizer::gatherImages         Gather residual images : [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2, /home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] onto :tst
20-Apr-2026 17:41:22 2026-04-20 17:41:22        INFO        task_tclean::MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0]
20-Apr-2026 17:41:22 2026-04-20 17:41:23        INFO        task_tclean::MultiTermMatrixCleaner::initialise()         Using a PSF patch of 80 pixels on each side for minor-cycle updates.
20-Apr-2026 17:41:22 2026-04-20 17:41:23        INFO        task_tclean::MatrixCleaner::validatePsf()         Peak of PSF = 1 at [512, 512]
20-Apr-2026 17:41:22 2026-04-20 17:41:23        INFO        task_tclean::MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak +        [1, -0.0185823
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::MultiTermMatrixCleaner::computeHessianPeak +         -0.0185823, 0.0267776]
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::SDAlgorithmBase::restore         [tst] : Restoring model image.
20-Apr-2026 17:41:46 2026-04-20 17:41:23        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2352)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 27.444 arcsec, 24.7765 arcsec, 60.5591 deg
20-Apr-2026 17:41:46 2026-04-20 17:41:23        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2352)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::SIImageStore::restore         Beam for chan : 0 : 27.444 arcsec, 24.7765 arcsec, 60.5591 deg
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.0974021 Jy/beam
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /home/casatest/work/test_task_tclean/tst.alpha.error
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::SDAlgorithmMSMFS::restore         Restored images :
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt0  (model=/home/casatest/work/test_task_tclean/tst.model.tt0)
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::SDAlgorithmMSMFS::restore         /home/casatest/work/test_task_tclean/tst.image.tt1  (model=/home/casatest/work/test_task_tclean/tst.model.tt1)
20-Apr-2026 17:41:46 2026-04-20 17:41:23        INFO        task_tclean::SDAlgorithmMSMFS::restore         
20-Apr-2026 17:41:46 2026-04-20 17:41:42        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:46 2026-04-20 17:41:42        INFO        ::casa::MPIServer-3        node 3 has completed its job
20-Apr-2026 17:41:46 2026-04-20 17:41:42        INFO        ::casa::MPIServer-1        node 1 has completed its job
20-Apr-2026 17:41:46 2026-04-20 17:41:42        INFO        ::casa::MPIServer-2        node 2 has completed its job
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        tclean::::casa        Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
20-Apr-2026 17:41:46 2026-04-20 17:41:43        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.weight.tt0', 'tst.weight.tt1', 'tst.weight.tt2', 'tst.pb.tt0', 'tst.model.tt0', 'tst.model.tt1', 'tst.residual.tt0', 'tst.residual.tt1', 'tst.image.tt0', 'tst.image.tt1', 'tst.alpha', 'tst.alpha.error']
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        tclean::::casa        Task tclean complete. Start time: 2026-04-20 17:41:01.540947 End time: 2026-04-20 17:41:43.290239
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        tclean::::casa        ##### End Task: tclean               #####
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        name::        No table opened.
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        showcache::        The Table Cache is empty.
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        tclean::::casa        ##########################################
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        tclean::::casa        ##### Begin Task: tclean             #####
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        tclean::::casa        tclean( vis='refim_oneshiftpoint.mosaic.ms', selectdata=True, field='0,1', spw='*', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst', imsize=1024, cell='10.0arcsec', phasecenter='J2000 19h59m28.5 +40d40m01.5', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='1.5GHz', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='mosaic', 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.1, normtype='flatnoise', deconvolver='mtmfs', scales=[], nterms=2, smallscalebias=0.0, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, outlierfile='', weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=10, 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 )
20-Apr-2026 17:41:46 2026-04-20 17:41:43        WARN        tclean::task_tclean::casa        Please consider using specmode=mvc with mosaic gridder as this gridder does not implement conjbeams
20-Apr-2026 17:41:46 2026-04-20 17:41:43        WARN        tclean::task_tclean::casa+         thus it needs a few major cycles to converge towards the correct answer
20-Apr-2026 17:41:46 2026-04-20 17:41:43        INFO        tclean::::casa        Verifying Input Parameters
20-Apr-2026 17:41:46 2026-04-20 17:41:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-3        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 3
20-Apr-2026 17:41:46 2026-04-20 17:41:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-2        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 2
20-Apr-2026 17:41:46 2026-04-20 17:41:45        INFO        MPICommandServer::serve::MPICommandServer::serve::casa::MPIServer-1        Control signal MPIEnvironment.command_handling_log_level = 'DEBUG' successfully handled by server 1
20-Apr-2026 17:41:46 2026-04-20 17:41:47        INFO        tclean::::casa        setupCluster, Setting up 3 engines.
20-Apr-2026 17:41:46 2026-04-20 17:41:47        INFO        SynthesisUtilMethods::continuumDataPartition         
20-Apr-2026 17:41:46 2026-04-20 17:41:47        INFO        SynthesisUtilMethods::continuumDataPartition +        Rows = 16851 25272 [P][M]: 0:2 ROWNUMBER() >= 16851 && ROWNUMBER() <= 25272
20-Apr-2026 17:41:46 2026-04-20 17:41:47        INFO        tclean::::casa        Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0,1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 8425', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0,1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 8426 && ROWNUMBER() <= 16850', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '0,1', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': 'refim_oneshiftpoint.mosaic.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '*', 'state': '', 'taql': 'ROWNUMBER() >= 16851 && ROWNUMBER() <= 25272', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
20-Apr-2026 17:41:46 2026-04-20 17:41:47        INFO        SynthesisImagerVi2::selectData         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0,1 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:41:46 2026-04-20 17:41:47        INFO        SynthesisImagerVi2::selectData           NRows selected : 25272
20-Apr-2026 17:41:46 2026-04-20 17:41:47        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:41:46 2026-04-20 17:41:47        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst] :
20-Apr-2026 17:41:55 2026-04-20 17:41:47        INFO        SynthesisImagerVi2::defineImage         Impars: start
20-Apr-2026 17:41:55 2026-04-20 17:41:47        INFO        SynthesisImagerVi2::defineImage         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00024e+08]
20-Apr-2026 17:41:55 2026-04-20 17:41:47        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst] with ftmachine : mosaicft
20-Apr-2026 17:41:55 2026-04-20 17:41:47        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:41:55 2026-04-20 17:41:47        INFO        VPSkyJones::VPSkyJones        PB used EVLA
20-Apr-2026 17:41:55 2026-04-20 17:41:47        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:41:55 2026-04-20 17:41:47        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:55 2026-04-20 17:41:48        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:55 2026-04-20 17:41:48        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:55 2026-04-20 17:41:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0,1 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:41:55 2026-04-20 17:41:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0,1 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:41:55 2026-04-20 17:41:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-3         Selecting via TaQL : ROWNUMBER() >= 16851 && ROWNUMBER() <= 25272 |   NRows selected : 8422
20-Apr-2026 17:41:55 2026-04-20 17:41:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-1         Selecting via TaQL : ROWNUMBER() >= 1 && ROWNUMBER() <= 8425 |   NRows selected : 8425
20-Apr-2026 17:41:55 2026-04-20 17:41:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         MS : refim_oneshiftpoint.mosaic.ms | Selecting on fields : 0,1 | Selecting on spw :* | [Opened in readonly mode]
20-Apr-2026 17:41:55 2026-04-20 17:41:48        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::selectData::MPIServer-2         Selecting via TaQL : ROWNUMBER() >= 8426 && ROWNUMBER() <= 16850 |   NRows selected : 8425
20-Apr-2026 17:41:55 2026-04-20 17:41:49        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:55 2026-04-20 17:41:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:41:55 2026-04-20 17:41:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] :
20-Apr-2026 17:41:55 2026-04-20 17:41:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Impars: start
20-Apr-2026 17:41:55 2026-04-20 17:41:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00024e+08]
20-Apr-2026 17:41:55 2026-04-20 17:41:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-1         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n1] with ftmachine : mosaicft
20-Apr-2026 17:41:55 2026-04-20 17:41:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-1         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:41:55 2026-04-20 17:41:49        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-1        PB used EVLA
20-Apr-2026 17:41:55 2026-04-20 17:41:49        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-1        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] :
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Impars: start
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00024e+08]
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-2         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n2] with ftmachine : mosaicft
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-2         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-2        PB used EVLA
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-2        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Define image coordinates for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] :
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Impars: start
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Shape: [1024, 1024, 1, 1] Spectral: [1.5e+09] at [0] with increment [9.00024e+08]
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::defineImage::MPIServer-3         Set Gridding options for [/home/casatest/work/test_task_tclean/tst.workdirectory/tst.n3] with ftmachine : mosaicft
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::getVPRecord::MPIServer-3         Using default Voltage Patterns from the VPManager
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::VPSkyJones::VPSkyJones::MPIServer-3        PB used EVLA
20-Apr-2026 17:41:55 2026-04-20 17:41:50        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::nSubCubeFitInMemory::MPIServer-3        Required memory: 0.3842 GB. Available mem.: 387.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
20-Apr-2026 17:41:55 2026-04-20 17:41:51        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:55 2026-04-20 17:41:53        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:55 2026-04-20 17:41:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-3         Set imaging weights : Natural weighting
20-Apr-2026 17:41:55 2026-04-20 17:41:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-1         Set imaging weights : Natural weighting
20-Apr-2026 17:41:55 2026-04-20 17:41:53        INFO        MPICommandServer::command_request_handler_service::SynthesisImagerVi2::weight()::MPIServer-2         Set imaging weights : Natural weighting
20-Apr-2026 17:41:55 2026-04-20 17:41:54        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:55 2026-04-20 17:41:54        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst] : mtmfs
20-Apr-2026 17:41:55 2026-04-20 17:41:54        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:55 2026-04-20 17:41:55        INFO        tclean::::casa        ...done
20-Apr-2026 17:41:55 2026-04-20 17:41:55        INFO        tclean::::casa        checkJobs. Blocking for nodes to finish
20-Apr-2026 17:41:56 2026-04-20 17:41:55        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-3         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:41:56 2026-04-20 17:41:55        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-1         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:41:56 2026-04-20 17:41:55        INFO        MPICommandServer::command_request_handler_service::SynthesisImager::makePSF::MPIServer-2         ----------------------------------------------------------- Make PSF ---------------------------------------------
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1168 MHz scaled to original frequency 1199.99
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [8]ELKEY 0_0_1 actualConvInd 0 pointer 1
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1488 MHz scaled to original frequency 1499.98
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-3         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-2         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:41:56 2026-04-20 17:41:56        INFO        MPICommandServer::command_request_handler_service::HetArrConvFunc::findConvFunction::MPIServer-1         convolution function support: [10]ELKEY 0_1_1 actualConvInd 1 pointer 1
20-Apr-2026 17:41:56 2026-04-20 17:41:57        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:56 2026-04-20 17:41:57        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:56 2026-04-20 17:41:57        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:56 2026-04-20 17:41:57        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-2        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:56 2026-04-20 17:41:57        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-3        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:41:56 2026-04-20 17:41:57        INFO1        MPICommandServer::command_request_handler_service::PBMATH1DEVLA::nearestVPArray::MPIServer-1        Using EVLA L band beam model of frequency  1808 MHz scaled to original frequency 1799.98
20-Apr-2026 17:42:03 Failing task since return code of [/export/cvpost135-nvme/casa/bamboohome/temp/CASASPECS-RELEASE675TESTCASALITHPY312-MTTR85D-3-ScriptBuildTask-10091471166486520582.sh] was 143 while expected 0
20-Apr-2026 17:42:03 Finished task 'MPI Tasks Tests on EL8' with result: Failed
20-Apr-2026 17:42:03 Running post build plugin 'Docker Container Cleanup'
20-Apr-2026 17:42:03 Running post build plugin 'NCover Results Collector'
20-Apr-2026 17:42:03 Running post build plugin 'Build Results Label Collector'
20-Apr-2026 17:42:03 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
20-Apr-2026 17:42:03 Attempting to generate stack trace and terminate spawned sub-processes of process id: 5454
20-Apr-2026 17:42:03 Running post build plugin 'Clover Results Collector'
20-Apr-2026 17:42:03 Running post build plugin 'npm Cache Cleanup'
20-Apr-2026 17:42:03 Running post build plugin 'Artifact Copier'
20-Apr-2026 17:42:03 Finalising the build...
20-Apr-2026 17:42:03 Stopping timer.
20-Apr-2026 17:42:03 Build CASASPECS-RELEASE675TESTCASALITHPY312-MTTR85D-3 completed.
20-Apr-2026 13:42:03 Found related process: pid: 565315 ppid: 1 pgid: 5427 %cpu: 0.0 %mem: 0.0 cmd: docker
20-Apr-2026 13:42:03 getStackTraceAndKillRelatedProcesses for 1 processes
20-Apr-2026 13:42:03 Executing kill -3 565315
20-Apr-2026 13:42:04 Running on server: post build plugin 'Build Hanging Detection Configuration'
20-Apr-2026 13:42:04 Running on server: post build plugin 'NCover Results Collector'
20-Apr-2026 13:42:04 Running on server: post build plugin 'Build Labeller'
20-Apr-2026 13:42:04 Running on server: post build plugin 'Clover Delta Calculator'
20-Apr-2026 13:42:04 Running on server: post build plugin 'Maven Dependencies Postprocessor'
20-Apr-2026 13:42:04 All post build plugins have finished
20-Apr-2026 13:42:04 Generating build results summary...
20-Apr-2026 13:42:04 Saving build results to disk...
20-Apr-2026 13:42:04 Store variable context...
20-Apr-2026 13:42:04 Finished building CASASPECS-RELEASE675TESTCASALITHPY312-MTTR85D-3.
20-Apr-2026 13:42:08 Killing: 565315
20-Apr-2026 13:42:08 Executing kill 565315
20-Apr-2026 13:42:13 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
20-Apr-2026 13:42:13 Has finished generating stack trace and terminating spawned sub-processes of process id: 5454