Build: #1 was successful

Job: Test Casalith Cvpost Many Linux 2.28 was successful

Stages & jobs

  1. Default Stage

Build log

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

06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        sdintimaging::::casa        Initializing SDINT Helper instance with tmp file tag 936286
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        sdintimaging::::casa         The center of psf coincides with int psf: (diffRA,diffDec)=( 0.0, 0.0)
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:28:30 2025-12-06 01:28:30        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        SubImageFactory::createImage        Creating image 'tst.sp_mfs_sdint.sd.cube.residual' of shape [800, 800, 1, 3]
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:28:30 2025-12-06 01:28:30        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        SubImageFactory::createImage        Creating image 'tst.sp_mfs_sdint.sd.cube.image' of shape [800, 800, 1, 3]
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:28:30 2025-12-06 01:28:30        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        SubImageFactory::createImage        Creating image 'tst.sp_mfs_sdint.sd.cube.psf' of shape [800, 800, 1, 3]
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        sdintimaging::::casa        Deleting tmp_936286_*plane
06-Dec-2025 01:28:30 2025-12-06 01:28:30        WARN        sdintimaging::task_sdintimaging::casa        The cube for the major cycle has only 3 channels. A minimum of nterms = 2 channels is required for an accurate polynomial fit, but where possible at least 5 to 10 channels that span the frequency range are prefered in order to properly encode frequency dependent intensity and weights.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        sdintimaging::::casa        There are 1 empty channels in the interferometer cube. These channels will be excluded from the feathering step.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        sdintimaging::::casa        The following channel weights/flags will be used in the feather step and minor cycle. Zero indicates channels that are empty in either the INT or SD input cubes and which will be excluded from the joint reconstruction. : [1. 1. 0.]
06-Dec-2025 01:28:30 2025-12-06 01:28:30        WARN        sdintimaging::::casa        There are channels that are filled with zeros either in the INT cube or the SD cube or both, and they will be ignored from the joint reconstruction. Please search the log file for the string "channel weights/flags" to find a listing of channels that are being used
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        sdintimaging::::casa        Feathering INT and SD residual images...
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        sdintimaging::::casa        Deleting tmp_641707_*plane
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:30 2025-12-06 01:28:30        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        imager::feather()        
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:28:32 2025-12-06 01:28:30        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:28:32 2025-12-06 01:28:31        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        sdintimaging::::casa        Deleting tmp_641707_*plane
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        imager::feather()        
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:28:32 2025-12-06 01:28:31        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        sdintimaging::::casa        Deleting tmp_641707_*plane
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:28:32 2025-12-06 01:28:31        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        imager::feather()        
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:28:32 2025-12-06 01:28:32        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        sdintimaging::::casa        Deleting tmp_641707_*plane
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        imager::feather()        
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:28:32 2025-12-06 01:28:32        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:28:42 2025-12-06 01:28:32        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:28:42 2025-12-06 01:28:32        INFO        SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
06-Dec-2025 01:28:42 2025-12-06 01:28:32        INFO        SynthesisUtilMethods::fitPsfBeam        Fitting PSF beam for Imagestore : tst.sp_mfs_sdint.joint.cube
06-Dec-2025 01:28:42 2025-12-06 01:28:32        WARN        SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2136)        PSF is blank for[C2:P0]
06-Dec-2025 01:28:42 2025-12-06 01:28:32        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.035807
06-Dec-2025 01:28:42 2025-12-06 01:28:32        INFO        SIImageStore::printBeamSet         Restoring Beams
06-Dec-2025 01:28:42 2025-12-06 01:28:32        INFO        SIImageStore::printBeamSet +        Chan    Freq   Vel Pol
06-Dec-2025 01:28:42 2025-12-06 01:28:32        INFO        SIImageStore::printBeamSet +           0 1.0e+09    33   I   77.5193 arcsec x   76.6073 arcsec pa= 11.1113 deg
06-Dec-2025 01:28:42 2025-12-06 01:28:32        INFO        SIImageStore::printBeamSet +           1 1.5e+09 -149846   I   51.6676 arcsec x   51.0710 arcsec pa= 11.3343 deg
06-Dec-2025 01:28:42 2025-12-06 01:28:32        INFO        SIImageStore::printBeamSet +           2 2.0e+09 -299726   I   77.5193 arcsec x   76.6073 arcsec pa= 11.1113 deg
06-Dec-2025 01:28:42 2025-12-06 01:28:32        INFO        sdintimaging::::casa        Calculate spectral PSFs and Taylor Residuals...
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SynthesisUtilMethods::fitPsfBeam        Fitting PSF beam for Imagestore : tst.sp_mfs_sdint.joint.multiterm
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.015988
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SIImageStore::printBeamSet         Beam : 62.8224 arcsec, 62.2044 arcsec, 13.3205 deg
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1.29169
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SynthesisDeconvolver::setupMask         [tst.sp_mfs_sdint.joint.multiterm] Setting up a mask from [papersky_standard.true.im.masklist]
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO                You've specified explicitly that the spectral axis should be regridded. However, the input image has a degenerate spectral axis and so it cannot be regridded. Instead, the resulting single output channel will be replicated 1 times in the output image.
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:28:42 2025-12-06 01:28:33        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        RegionTextParser::_determineVersion        RegionTextParser::_determineVersion: Found spec version 0
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        AsciiRegionFileParser::_parse        Combined 5 image regions (which excludes any annotation regions)
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SynthesisDeconvolver::setupMask         ----------------------------------------------------------------------------------------------------------------------------------------
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SynthesisDeconvolver::setupMask         [tst.sp_mfs_sdint.joint.multiterm] Number of pixels in the clean mask : 44220 out of a total of 640000 pixels. [ 6.90937 % ]
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.29169, over full image : 1.29169
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        sdintimaging::::casa        Minor Cycle controls : {'cycleniter': 50, 'cyclethreshold': 0.1430157572031021, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdint.joint.multiterm] Run mtmfs minor-cycle | CycleThreshold=0.143016, CycleNiter=50, Gain=0.1
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0, 12, 20, 40, 60, 80, 100]
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 1 = 0 pixels with bias = 1
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 2 = 12 pixels with bias = 1
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 3 = 20 pixels with bias = 1
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 4 = 40 pixels with bias = 1
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 5 = 60 pixels with bias = 1
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 6 = 80 pixels with bias = 1
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 7 = 100 pixels with bias = 1
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 800 pixels on each side for minor-cycle updates.
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 0.990624 at [400, 400]
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749917 GHz -> 2.25008GHz.Fractional Bandwidth : 100.011 %.Reference Frequency for Taylor Expansion : 1.5GHz.
06-Dec-2025 01:28:42 2025-12-06 01:28:33        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.990624, -0.165207
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.165207, 0.0550629]
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 12 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.308491, -0.0629614
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.0629614, 0.0209874]
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 20 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.166511, -0.0351309
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.0351309, 0.0117106]
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 40 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.0568644, -0.0130262
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.0130262, 0.00434238]
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 60 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.0243685, -0.00596235
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00596235, 0.00198765]
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 80 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.0117886, -0.00298506
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00298506, 0.000995135]
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 100 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.006295, -0.00163772
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00163772, 0.000545977]
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
06-Dec-2025 01:28:42 2025-12-06 01:28:34        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 1.30392
06-Dec-2025 01:28:42 2025-12-06 01:28:39        INFO                [20] Res: 1.22854 Max: 1.68963 Gain: 0.1 Pos: [296, 422] Scale: 100 Coeffs: 18.7747  9.38979 
06-Dec-2025 01:28:57 2025-12-06 01:28:43        INFO                [40] Res: 0.856896 Max: 0.77222 Gain: 0.1 Pos: [267, 383] Scale: 100 Coeffs: 13.6767  10.3534 
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO                [50] Res: 0.717276 Max: 0.55649 Gain: 0.1 Pos: [400, 367] Scale: 0 Coeffs: 0.591667  -0.862934 
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 1.23248  [12]: 0  [20]: 0  [40]: 0  [60]: 0  [80]: 0  [100]: 80.9651 (in this run)
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 82.1976  [1]: 38.9155
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdint.joint.multiterm] iters=0->50 [50], model=0->82.1937, peakres=1.29169->0.710551, Reached cycleniter.
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 50 iterations.
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO        task_sdintimaging::image::_setrestoringbeam         Will remove any existing beams from image
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO        task_sdintimaging::::        Deleted restoring beam(s)
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO        task_sdintimaging::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
06-Dec-2025 01:28:57 2025-12-06 01:28:45        INFO        task_sdintimaging::SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        sdintimaging::::casa        Deleting tmp_641707_*plane
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        imager::feather()        
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:28:57 2025-12-06 01:28:46        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:28:57 2025-12-06 01:28:46        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        sdintimaging::::casa        Deleting tmp_641707_*plane
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        imager::feather()        
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:28:57 2025-12-06 01:28:47        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        sdintimaging::::casa        getSummary call: fullsummary=False
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.71052, over full image : 0.71052
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        sdintimaging::::casa        Minor Cycle controls : {'cycleniter': 50, 'cyclethreshold': 0.07866837829351425, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdint.joint.multiterm] Run mtmfs minor-cycle | CycleThreshold=0.0786684, CycleNiter=50, Gain=0.1
06-Dec-2025 01:28:57 2025-12-06 01:28:47        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749917 GHz -> 2.25008GHz.Fractional Bandwidth : 100.011 %.Reference Frequency for Taylor Expansion : 1.5GHz.
06-Dec-2025 01:28:57 2025-12-06 01:28:48        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
06-Dec-2025 01:28:57 2025-12-06 01:28:48        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 0.717245
06-Dec-2025 01:28:57 2025-12-06 01:28:50        INFO                [60] Res: 0.648191 Max: 0.437314 Gain: 0.1 Pos: [288, 468] Scale: 100 Coeffs: 8.08301  -0.964005 
06-Dec-2025 01:28:57 2025-12-06 01:28:54        INFO                [80] Res: 0.488584 Max: 0.267872 Gain: 0.1 Pos: [345, 412] Scale: 100 Coeffs: 7.55851  4.07243 
06-Dec-2025 01:28:57 2025-12-06 01:28:58        INFO                [100] Res: 0.386002 Max: 0.15873 Gain: 0.1 Pos: [282, 320] Scale: 100 Coeffs: 3.88515  -4.2365 
06-Dec-2025 01:28:57 2025-12-06 01:28:58        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
06-Dec-2025 01:28:57 2025-12-06 01:28:58        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 2.03727  [12]: 0  [20]: 0  [40]: 0  [60]: 0  [80]: 0  [100]: 104.609 (in this run)
06-Dec-2025 01:28:57 2025-12-06 01:28:58        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 106.642  [1]: 40.9103
06-Dec-2025 01:28:57 2025-12-06 01:28:58        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdint.joint.multiterm] iters=0->50 [50], model=82.198->106.641, peakres=0.71052->0.382383, Reached cycleniter.
06-Dec-2025 01:29:01 2025-12-06 01:28:58        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 100 iterations.
06-Dec-2025 01:29:01 2025-12-06 01:28:58        INFO        task_sdintimaging::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 3 -------------------------------------
06-Dec-2025 01:29:01 2025-12-06 01:28:58        INFO        task_sdintimaging::SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:29:01 2025-12-06 01:28:59        INFO        sdintimaging::::casa        Deleting tmp_641707_*plane
06-Dec-2025 01:29:01 2025-12-06 01:28:59        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:01 2025-12-06 01:28:59        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:01 2025-12-06 01:28:59        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:01 2025-12-06 01:28:59        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:01 2025-12-06 01:28:59        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:01 2025-12-06 01:28:59        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        imager::feather()        
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:01 2025-12-06 01:29:00        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        sdintimaging::::casa        Deleting tmp_641707_*plane
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        ImageRegion::fromRecord        Creating image 'tmp_641707_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        imager::feather()        
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:01 2025-12-06 01:29:00        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:01 2025-12-06 01:29:00        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        sdintimaging::::casa        getSummary call: fullsummary=False
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.38229, over full image : 0.38229
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        sdintimaging::::casa        Reached global stopping criterion : iteration limit
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SDAlgorithmBase::restore         [tst.sp_mfs_sdint.joint.multiterm] : Restoring model image.
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SIImageStore::restore         Beam for chan : 0 : 62.8224 arcsec, 62.2044 arcsec, 13.3205 deg
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SIImageStore::restore         Beam for chan : 0 : 62.8224 arcsec, 62.2044 arcsec, 13.3205 deg
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.0384815 Jy/beam
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.alpha.error
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SDAlgorithmMSMFS::restore         Restored images :
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SDAlgorithmMSMFS::restore         /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.image.tt0  (model=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.model.tt0)
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SDAlgorithmMSMFS::restore         /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.image.tt1  (model=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.model.tt1)
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        SDAlgorithmMSMFS::restore         
06-Dec-2025 01:29:01 master 1 init 1
06-Dec-2025 01:29:01 2025-12-06 01:29:01        INFO        sdintimaging::::casa        Deleting tmp_641707_*plane
06-Dec-2025 01:29:03 2025-12-06 01:29:01        INFO        sdintimaging::::casa        Searching for images with prefix 'tst.sp_mfs_sdint'... Found these, writing history into them: ['tst.sp_mfs_sdint.int.cube.pb', 'tst.sp_mfs_sdint.int.cube.psf', 'tst.sp_mfs_sdint.int.cube.sumwt', 'tst.sp_mfs_sdint.int.cube.residual', 'tst.sp_mfs_sdint.joint.multiterm.psf.tt0', 'tst.sp_mfs_sdint.joint.multiterm.sumwt.tt0', 'tst.sp_mfs_sdint.joint.multiterm.psf.tt1', 'tst.sp_mfs_sdint.joint.multiterm.sumwt.tt1', 'tst.sp_mfs_sdint.joint.multiterm.psf.tt2', 'tst.sp_mfs_sdint.joint.multiterm.sumwt.tt2', 'tst.sp_mfs_sdint.joint.multiterm.pb.tt0', 'tst.sp_mfs_sdint.joint.multiterm.residual.tt0', 'tst.sp_mfs_sdint.joint.multiterm.residual.tt1', 'tst.sp_mfs_sdint.sd.cube.residual', 'tst.sp_mfs_sdint.sd.cube.image', 'tst.sp_mfs_sdint.sd.cube.psf', 'tst.sp_mfs_sdint.joint.cube.psf', 'tst.sp_mfs_sdint.joint.multiterm.mask', 'tst.sp_mfs_sdint.joint.multiterm.model.tt0', 'tst.sp_mfs_sdint.joint.multiterm.model.tt1', 'tst.sp_mfs_sdint.int.cube.model', 'tst.sp_mfs_sdint.sd.cube.model', 'tst.sp_mfs_sdint.joint.cube.residual', 'tst.sp_mfs_sdint.joint.multiterm.image.tt0', 'tst.sp_mfs_sdint.joint.multiterm.image.tt1', 'tst.sp_mfs_sdint.joint.multiterm.alpha', 'tst.sp_mfs_sdint.joint.multiterm.alpha.error']
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        Task sdintimaging complete. Start time: 2025-12-05 20:28:26.735904 End time: 2025-12-05 20:29:01.760626
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        ##### End Task: sdintimaging         #####
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        ##########################################
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        name::        No table opened.
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        showcache::        The Table Cache is empty.
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        [ check_ret_structure ] Return dictionary struture check: type=reduced ( Pass : found all expected keys  )
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa+        [ check_ims ] Image made : ['tst.sp_mfs_sdint.joint.multiterm.psf.tt0', 'tst.sp_mfs_sdint.joint.multiterm.residual.tt0', 'tst.sp_mfs_sdint.joint.multiterm.image.tt0', 'tst.sp_mfs_sdint.joint.multiterm.image.tt1', 'tst.sp_mfs_sdint.joint.multiterm.alpha'] =  [True, True, True, True, True] ( Pass : should all be True )
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa+        [check_history] All expected history entries found. (Pass)
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdint.joint.multiterm.psf.tt0 : Value is 0.9906240701675415 at [400, 400, 0, 0] ( Pass : should be 0.991 , Epsilon: 0.05)
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdint.joint.multiterm.image.tt0 : Value is 1.1378769874572754 at [350, 433, 0, 0] ( Pass : should be 1.138 , Epsilon: 0.05)
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdint.joint.multiterm.image.tt0 : Value is 0.3725101351737976 at [300, 400, 0, 0] ( Pass : should be 0.373 , Epsilon: 0.05)
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdint.joint.multiterm.alpha : Value is -1.3045321702957153 at [350, 433, 0, 0] ( Pass : should be -1.3 , Epsilon: 0.05)
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdint.joint.multiterm.alpha : Value is 0.07270953059196472 at [300, 400, 0, 0] ( Pass : should be 0.0727 , Epsilon: 0.05)
06-Dec-2025 01:29:03 .2025-12-06 01:29:02        INFO        sdintimaging::::casa        ##########################################
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        ##### Begin Task: sdintimaging       #####
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        sdintimaging( usedata='sd', sdimage='papersky_standard.sdimage', sdpsf='papersky_standard.sdpsf', sdgain=1.0, dishdia=100.0, vis='papersky_standard.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst.sp_mfs_sdonly', imsize=800, cell='9.0arcsec', phasecenter='J2000 19:59:28.500 +40.44.01.50', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='1.5GHz', nchan=3, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='nearest', perchanweightdensity=True, gridder='standard', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=-0.1, deconvolver='mtmfs', scales=[0, 12, 20, 40, 60, 80, 100], nterms=2, smallscalebias=0.0, restoration=True, restoringbeam=[], pbcor=False, weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=100, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=50, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, fullsummary=False, nmajor=-1, usemask='user', mask='papersky_standard.true.im.masklist', 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, calcres=True, calcpsf=True )
06-Dec-2025 01:29:03 2025-12-06 01:29:02        WARN        sdintimaging::task_sdintimaging::casa        The Single-Dish-Only mode of sdintimaging is better supported via the deconvolve task which supports spectral cube, mfs and multi-term mfs deconvolution in the image domain alone. The deconvolve task is the more appropriate version to use for stand-alone image-domain deconvolution, and will not have the bookkeeping overheads currently present in the sdintimaging task's sd-only mode. Please note that the 'sd' option of the sdintimaging task will be removed in a subsequent release.  Please refer to the task deconvolve documentation for instructions on how to prepare image and psf cubes for the deconvolve task for all these modes.
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        Initializing SDINT Helper instance with tmp file tag 710787
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        INT cube setup ....
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        Verifying Input Parameters
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::selectData         MS : papersky_standard.ms | [Opened in readonly mode]
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::selectData           NRows selected : 6318
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.sp_mfs_sdonly.int.cube] :
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.sp_mfs_sdonly.int.cube] :
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        MSTransformRegridder::calcChanFreqs         phaseCenter='Direction: [0.377371, -0.657097, 0.652545]'  Channels equidistant in freq
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        MSTransformRegridder::calcChanFreqs+         Central frequency (in output frame) = 1.49983e+09 Hz
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        MSTransformRegridder::calcChanFreqs+         Width of central channel (in output frame) = 4.99945e+08 Hz
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        MSTransformRegridder::calcChanFreqs+         Number of channels = 3
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        MSTransformRegridder::calcChanFreqs+         Total width of SPW (in output frame) = 1.49983e+09 Hz
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        MSTransformRegridder::calcChanFreqs+         Lower edge = 7.49917e+08 Hz, upper edge = 2.24975e+09 Hz
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::defineImage         Impars: start
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::defineImage         Shape: [800, 800, 1, 3] Spectral: [9.99889e+08] at [0] with increment [4.99945e+08]
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.sp_mfs_sdonly.int.cube] with ftmachine : gridft
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        sdintimaging::::casa        CFCache already exists
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Natural weighting
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImager::makePSF         ----------------------------------------------------------- Make PSF ---------------------------------------------
06-Dec-2025 01:29:03 2025-12-06 01:29:02        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:29:03 2025-12-06 01:29:03        INFO        SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
06-Dec-2025 01:29:03 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:29:03 2025-12-06 01:29:03        INFO        sdintimaging::::casa        Copying restoring beam(s) for 3 channel(s) from tst.sp_mfs_sdonly.int.cube.psf to tst.sp_mfs_sdonly.int.cube.residual
06-Dec-2025 01:29:03 2025-12-06 01:29:03        INFO        image::_setrestoringbeam         Will copy beams from another image to this image
06-Dec-2025 01:29:03 2025-12-06 01:29:03        INFO        BeamManipulator::set        Set image beam set
06-Dec-2025 01:29:03 2025-12-06 01:29:03        INFO        sdintimaging::::casa        Combined image setup ....
06-Dec-2025 01:29:03 2025-12-06 01:29:03        INFO        sdintimaging::::casa        Verifying Input Parameters
06-Dec-2025 01:29:03 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::selectData         MS : papersky_standard.ms | [Opened in readonly mode]
06-Dec-2025 01:29:03 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::selectData           NRows selected : 6318
06-Dec-2025 01:29:06 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.sp_mfs_sdonly.joint.multiterm] :
06-Dec-2025 01:29:06 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.sp_mfs_sdonly.joint.multiterm] :
06-Dec-2025 01:29:06 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::defineImage         Impars: start
06-Dec-2025 01:29:06 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::defineImage         Shape: [800, 800, 1, 1] Spectral: [1.5e+09] at [0] with increment [1.50017e+09]
06-Dec-2025 01:29:06 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.sp_mfs_sdonly.joint.multiterm] with ftmachine : gridft
06-Dec-2025 01:29:06 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.2266 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
06-Dec-2025 01:29:06 2025-12-06 01:29:03        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Natural weighting
06-Dec-2025 01:29:06 2025-12-06 01:29:03        INFO        SynthesisImager::makePSF         ----------------------------------------------------------- Make PSF ---------------------------------------------
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStoreMultiTerm::calcSensitivity +        [37908, -4.18539
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStoreMultiTerm::calcSensitivity +         -4.18539, 2807.38]
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStoreMultiTerm::calcSensitivity +        [2.63797e-05, 3.93281e-08
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStoreMultiTerm::calcSensitivity +         3.93281e-08, 0.000356204]
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst.sp_mfs_sdonly.joint.multiterm][Taylor0] Theoretical sensitivity (Jy/bm):0.00513611
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst.sp_mfs_sdonly.joint.multiterm][Taylor1] Theoretical sensitivity (Jy/bm):0.0188734
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749917 GHz -> 2.25008GHz.Fractional Bandwidth : 100.011 %.Reference Frequency for Taylor Expansion : 1.5GHz.
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.015293
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SIImageStore::printBeamSet         Beam : 53.662 arcsec, 53.2099 arcsec, 22.4308 deg
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SynthesisImagerVi2::makePrimaryBeam         vi2 : Evaluating Primary Beam model onto image grid(s)
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.sp_mfs_sdonly.joint.multiterm] : mtmfs
06-Dec-2025 01:29:06 2025-12-06 01:29:04        INFO        SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        sdintimaging::::casa        SD cube setup ....
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        sdintimaging::::casa        Initializing SDINT Helper instance with tmp file tag 509516
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        sdintimaging::::casa         The center of psf coincides with int psf: (diffRA,diffDec)=( 0.0, 0.0)
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:29:06 2025-12-06 01:29:05        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:29:06 2025-12-06 01:29:05        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        SubImageFactory::createImage        Creating image 'tst.sp_mfs_sdonly.sd.cube.residual' of shape [800, 800, 1, 3]
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:29:06 2025-12-06 01:29:05        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:29:06 2025-12-06 01:29:05        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        SubImageFactory::createImage        Creating image 'tst.sp_mfs_sdonly.sd.cube.image' of shape [800, 800, 1, 3]
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:29:06 2025-12-06 01:29:05        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:29:06 2025-12-06 01:29:05        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        SubImageFactory::createImage        Creating image 'tst.sp_mfs_sdonly.sd.cube.psf' of shape [800, 800, 1, 3]
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        sdintimaging::::casa        Deleting tmp_509516_*plane
06-Dec-2025 01:29:06 2025-12-06 01:29:05        WARN        sdintimaging::task_sdintimaging::casa        The cube for the major cycle has only 3 channels. A minimum of nterms = 2 channels is required for an accurate polynomial fit, but where possible at least 5 to 10 channels that span the frequency range are prefered in order to properly encode frequency dependent intensity and weights.
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        sdintimaging::::casa        Feathering INT and SD residual images...
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
06-Dec-2025 01:29:06 2025-12-06 01:29:05        INFO        SynthesisUtilMethods::fitPsfBeam        Fitting PSF beam for Imagestore : tst.sp_mfs_sdonly.joint.cube
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.151096
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SIImageStore::printBeamSet         Restoring Beams
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SIImageStore::printBeamSet +        Chan    Freq   Vel Pol
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SIImageStore::printBeamSet +           0 1.0e+09    33   I  664.4244 arcsec x  664.4244 arcsec pa=-24.7813 deg
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SIImageStore::printBeamSet +           1 1.5e+09 -149846   I  424.2759 arcsec x  424.2759 arcsec pa= 52.6739 deg
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SIImageStore::printBeamSet +           2 2.0e+09 -299726   I  320.2759 arcsec x  320.2759 arcsec pa= 57.9766 deg
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        sdintimaging::::casa        Calculate spectral PSFs and Taylor Residuals...
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SynthesisUtilMethods::fitPsfBeam        Fitting PSF beam for Imagestore : tst.sp_mfs_sdonly.joint.multiterm
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.057553
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SIImageStore::printBeamSet         Beam : 430.287 arcsec, 430.287 arcsec, -27.0128 deg
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 19.665
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        SynthesisDeconvolver::setupMask         [tst.sp_mfs_sdonly.joint.multiterm] Setting up a mask from [papersky_standard.true.im.masklist]
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO                You've specified explicitly that the spectral axis should be regridded. However, the input image has a degenerate spectral axis and so it cannot be regridded. Instead, the resulting single output channel will be replicated 1 times in the output image.
06-Dec-2025 01:29:06 2025-12-06 01:29:06        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:29:06 2025-12-06 01:29:06        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        RegionTextParser::_determineVersion        RegionTextParser::_determineVersion: Found spec version 0
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        AsciiRegionFileParser::_parse        Combined 5 image regions (which excludes any annotation regions)
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        SynthesisDeconvolver::setupMask         ----------------------------------------------------------------------------------------------------------------------------------------
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        SynthesisDeconvolver::setupMask         [tst.sp_mfs_sdonly.joint.multiterm] Number of pixels in the clean mask : 44220 out of a total of 640000 pixels. [ 6.90937 % ]
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 19.665, over full image : 19.665
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        sdintimaging::::casa        Minor Cycle controls : {'cycleniter': 50, 'cyclethreshold': 0.9832497835159302, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdonly.joint.multiterm] Run mtmfs minor-cycle | CycleThreshold=0.98325, CycleNiter=50, Gain=0.1
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0, 12, 20, 40, 60, 80, 100]
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 1 = 0 pixels with bias = 1
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 2 = 12 pixels with bias = 1
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 3 = 20 pixels with bias = 1
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 4 = 40 pixels with bias = 1
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 5 = 60 pixels with bias = 1
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 6 = 80 pixels with bias = 1
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 7 = 100 pixels with bias = 1
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 800 pixels on each side for minor-cycle updates.
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [400, 400]
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749917 GHz -> 2.25008GHz.Fractional Bandwidth : 100.011 %.Reference Frequency for Taylor Expansion : 1.5GHz.
06-Dec-2025 01:29:30 2025-12-06 01:29:06        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [1, -0.000110958
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.000110958, 0.0740577]
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 12 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.94427, -0.00762221
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00762221, 0.0698511]
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 20 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.859381, -0.0178902
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.0178902, 0.0635511]
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 40 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.608886, -0.0376936
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.0376936, 0.0456457]
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 60 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.416334, -0.039146
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.039146, 0.032104]
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 80 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.291608, -0.0328658
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.0328658, 0.0229904]
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 100 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.211678, -0.0260793
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.0260793, 0.0169138]
06-Dec-2025 01:29:30 2025-12-06 01:29:07        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
06-Dec-2025 01:29:30 2025-12-06 01:29:08        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 19.665
06-Dec-2025 01:29:30 2025-12-06 01:29:12        INFO                [20] Res: 5.10815 Max: 77.3431 Gain: 0.1 Pos: [298, 441] Scale: 100 Coeffs: 18.9288  -1.48036 
06-Dec-2025 01:29:30 2025-12-06 01:29:16        INFO                [40] Res: 2.71228 Max: 16.7033 Gain: 0.1 Pos: [358, 428] Scale: 100 Coeffs: 8.8508  -0.25997 
06-Dec-2025 01:29:30 2025-12-06 01:29:18        INFO                [50] Res: 2.13701 Max: 8.85767 Gain: 0.1 Pos: [359, 434] Scale: 100 Coeffs: 6.47337  0.0373564 
06-Dec-2025 01:29:30 2025-12-06 01:29:18        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
06-Dec-2025 01:29:30 2025-12-06 01:29:18        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0  [12]: 0  [20]: 0  [40]: 0  [60]: 0  [80]: 0  [100]: 106.822 (in this run)
06-Dec-2025 01:29:30 2025-12-06 01:29:18        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 106.822  [1]: -8.01212
06-Dec-2025 01:29:30 2025-12-06 01:29:18        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdonly.joint.multiterm] iters=0->50 [50], model=0->106.818, peakres=19.665->2.13701, Reached cycleniter.
06-Dec-2025 01:29:30 2025-12-06 01:29:18        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 50 iterations.
06-Dec-2025 01:29:30 2025-12-06 01:29:18        INFO        task_sdintimaging::image::_setrestoringbeam         Will remove any existing beams from image
06-Dec-2025 01:29:30 2025-12-06 01:29:18        INFO        task_sdintimaging::::        Deleted restoring beam(s)
06-Dec-2025 01:29:30 2025-12-06 01:29:20        INFO        sdintimaging::::casa        getSummary call: fullsummary=False
06-Dec-2025 01:29:30 2025-12-06 01:29:20        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 2.13701, over full image : 2.13701
06-Dec-2025 01:29:30 2025-12-06 01:29:20        INFO        sdintimaging::::casa        Minor Cycle controls : {'cycleniter': 50, 'cyclethreshold': 0.10685043781995773, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
06-Dec-2025 01:29:30 2025-12-06 01:29:20        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdonly.joint.multiterm] Run mtmfs minor-cycle | CycleThreshold=0.10685, CycleNiter=50, Gain=0.1
06-Dec-2025 01:29:30 2025-12-06 01:29:20        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749917 GHz -> 2.25008GHz.Fractional Bandwidth : 100.011 %.Reference Frequency for Taylor Expansion : 1.5GHz.
06-Dec-2025 01:29:30 2025-12-06 01:29:20        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
06-Dec-2025 01:29:30 2025-12-06 01:29:20        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 2.13701
06-Dec-2025 01:29:30 2025-12-06 01:29:22        INFO                [60] Res: 1.84557 Max: 5.16861 Gain: 0.1 Pos: [382, 364] Scale: 100 Coeffs: 4.88817  -0.422475 
06-Dec-2025 01:29:30 2025-12-06 01:29:26        INFO                [80] Res: 1.31836 Max: 2.08425 Gain: 0.1 Pos: [311, 377] Scale: 0 Coeffs: -1.2825  2.43402 
06-Dec-2025 01:29:30 2025-12-06 01:29:31        INFO                [100] Res: 1.00376 Max: 1.09028 Gain: 0.1 Pos: [356, 431] Scale: 40 Coeffs: 1.32615  -0.179836 
06-Dec-2025 01:29:30 2025-12-06 01:29:31        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
06-Dec-2025 01:29:33 2025-12-06 01:29:31        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: -0.550933  [12]: 0  [20]: 0  [40]: 0.453056  [60]: 0.512442  [80]: 0.407626  [100]: 122.214 (in this run)
06-Dec-2025 01:29:33 2025-12-06 01:29:31        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 123.031  [1]: -5.31311
06-Dec-2025 01:29:33 2025-12-06 01:29:31        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdonly.joint.multiterm] iters=0->50 [50], model=106.823->123.028, peakres=2.13701->1.00376, Reached cycleniter.
06-Dec-2025 01:29:33 2025-12-06 01:29:31        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 100 iterations.
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        sdintimaging::::casa        getSummary call: fullsummary=False
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.00376, over full image : 1.00376
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        sdintimaging::::casa        Reached global stopping criterion : iteration limit
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SDAlgorithmBase::restore         [tst.sp_mfs_sdonly.joint.multiterm] : Restoring model image.
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SIImageStore::restore         Beam for chan : 0 : 430.287 arcsec, 430.287 arcsec, -27.0128 deg
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SIImageStore::restore         Beam for chan : 0 : 430.287 arcsec, 430.287 arcsec, -27.0128 deg
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.100376 Jy/beam
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdonly.joint.multiterm.alpha.error
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SDAlgorithmMSMFS::restore         Restored images :
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SDAlgorithmMSMFS::restore         /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdonly.joint.multiterm.image.tt0  (model=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdonly.joint.multiterm.model.tt0)
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SDAlgorithmMSMFS::restore         /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdonly.joint.multiterm.image.tt1  (model=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdonly.joint.multiterm.model.tt1)
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        SDAlgorithmMSMFS::restore         
06-Dec-2025 01:29:33 master 1 init 1
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        sdintimaging::::casa        Deleting tmp_710787_*plane
06-Dec-2025 01:29:33 2025-12-06 01:29:32        INFO        sdintimaging::::casa        Searching for images with prefix 'tst.sp_mfs_sdonly'... Found these, writing history into them: ['tst.sp_mfs_sdonly.int.cube.pb', 'tst.sp_mfs_sdonly.int.cube.psf', 'tst.sp_mfs_sdonly.int.cube.sumwt', 'tst.sp_mfs_sdonly.int.cube.residual', 'tst.sp_mfs_sdonly.joint.multiterm.psf.tt0', 'tst.sp_mfs_sdonly.joint.multiterm.sumwt.tt0', 'tst.sp_mfs_sdonly.joint.multiterm.psf.tt1', 'tst.sp_mfs_sdonly.joint.multiterm.sumwt.tt1', 'tst.sp_mfs_sdonly.joint.multiterm.psf.tt2', 'tst.sp_mfs_sdonly.joint.multiterm.sumwt.tt2', 'tst.sp_mfs_sdonly.joint.multiterm.pb.tt0', 'tst.sp_mfs_sdonly.joint.multiterm.residual.tt0', 'tst.sp_mfs_sdonly.joint.multiterm.residual.tt1', 'tst.sp_mfs_sdonly.sd.cube.residual', 'tst.sp_mfs_sdonly.sd.cube.image', 'tst.sp_mfs_sdonly.sd.cube.psf', 'tst.sp_mfs_sdonly.joint.cube.psf', 'tst.sp_mfs_sdonly.joint.multiterm.mask', 'tst.sp_mfs_sdonly.joint.multiterm.model.tt0', 'tst.sp_mfs_sdonly.joint.multiterm.model.tt1', 'tst.sp_mfs_sdonly.int.cube.model', 'tst.sp_mfs_sdonly.sd.cube.model', 'tst.sp_mfs_sdonly.joint.cube.residual', 'tst.sp_mfs_sdonly.joint.multiterm.image.tt0', 'tst.sp_mfs_sdonly.joint.multiterm.image.tt1', 'tst.sp_mfs_sdonly.joint.multiterm.alpha', 'tst.sp_mfs_sdonly.joint.multiterm.alpha.error']
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa        Task sdintimaging complete. Start time: 2025-12-05 20:29:01.888512 End time: 2025-12-05 20:29:33.026120
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa        ##### End Task: sdintimaging         #####
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa        ##########################################
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        name::        No table opened.
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        showcache::        The Table Cache is empty.
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa        [ check_ret_structure ] Return dictionary struture check: type=reduced ( Pass : found all expected keys  )
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa+        [ check_ims ] Image made : ['tst.sp_mfs_sdonly.joint.multiterm.psf.tt0', 'tst.sp_mfs_sdonly.joint.multiterm.residual.tt0', 'tst.sp_mfs_sdonly.joint.multiterm.image.tt0', 'tst.sp_mfs_sdonly.joint.multiterm.image.tt1', 'tst.sp_mfs_sdonly.joint.multiterm.alpha'] =  [True, True, True, True, True] ( Pass : should all be True )
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa+        [check_history] All expected history entries found. (Pass)
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdonly.joint.multiterm.psf.tt0 : Value is 1.0 at [400, 400, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdonly.joint.multiterm.image.tt0 : Value is 8.102530479431152 at [350, 433, 0, 0] ( Pass : should be 7.91 , Epsilon: 0.05)
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdonly.joint.multiterm.image.tt0 : Value is 15.698700904846191 at [300, 400, 0, 0] ( Pass : should be 15.3 , Epsilon: 0.05)
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdonly.joint.multiterm.alpha : Value is -0.13693596422672272 at [350, 433, 0, 0] ( Pass : should be -0.137 , Epsilon: 0.05)
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa+        [ check_pix_val ] tst.sp_mfs_sdonly.joint.multiterm.alpha : Value is 0.01598879136145115 at [300, 400, 0, 0] ( Pass : should be 0.018 , Epsilon: 0.05)
06-Dec-2025 01:29:33 .2025-12-06 01:29:33        INFO        sdintimaging::::casa        ##########################################
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa        ##### Begin Task: sdintimaging       #####
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa        sdintimaging( usedata='sdint', sdimage='papersky_standard.sdimage', sdpsf='papersky_standard.sdpsf', sdgain=1.0, dishdia=100.0, vis='papersky_standard.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='tst.sp_mfs_sdint', imsize=800, cell='9.0arcsec', phasecenter='J2000 19:59:28.500 +40.44.01.50', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='1.5GHz', nchan=3, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='nearest', perchanweightdensity=True, gridder='standard', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=-0.1, deconvolver='mtmfs', scales=[0, 12, 20, 40, 60, 80, 100], nterms=2, smallscalebias=0.0, restoration=True, restoringbeam=[], pbcor=False, weighting='natural', robust=0.5, noise='1.0Jy', npixels=0, uvtaper=[''], niter=100, gain=0.1, threshold=0.0, nsigma=0.0, cycleniter=20, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, fullsummary=False, nmajor=2, usemask='user', mask='papersky_standard.true.im.masklist', 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, calcres=True, calcpsf=True )
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa        Initializing SDINT Helper instance with tmp file tag 477982
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa        INT cube setup ....
06-Dec-2025 01:29:33 2025-12-06 01:29:33        INFO        sdintimaging::::casa        Verifying Input Parameters
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::selectData         MS : papersky_standard.ms | [Opened in readonly mode]
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::selectData           NRows selected : 6318
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.sp_mfs_sdint.int.cube] :
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.sp_mfs_sdint.int.cube] :
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        MSTransformRegridder::calcChanFreqs         phaseCenter='Direction: [0.377371, -0.657097, 0.652545]'  Channels equidistant in freq
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        MSTransformRegridder::calcChanFreqs+         Central frequency (in output frame) = 1.49983e+09 Hz
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        MSTransformRegridder::calcChanFreqs+         Width of central channel (in output frame) = 4.99945e+08 Hz
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        MSTransformRegridder::calcChanFreqs+         Number of channels = 3
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        MSTransformRegridder::calcChanFreqs+         Total width of SPW (in output frame) = 1.49983e+09 Hz
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        MSTransformRegridder::calcChanFreqs+         Lower edge = 7.49917e+08 Hz, upper edge = 2.24975e+09 Hz
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::defineImage         Impars: start
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::defineImage         Shape: [800, 800, 1, 3] Spectral: [9.99889e+08] at [0] with increment [4.99945e+08]
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.sp_mfs_sdint.int.cube] with ftmachine : gridft
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        sdintimaging::::casa        CFCache already exists
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Natural weighting
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImager::makePSF         ----------------------------------------------------------- Make PSF ---------------------------------------------
06-Dec-2025 01:29:36 2025-12-06 01:29:33        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:29:36 2025-12-06 01:29:34        INFO        SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
06-Dec-2025 01:29:36 2025-12-06 01:29:34        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        sdintimaging::::casa        Copying restoring beam(s) for 3 channel(s) from tst.sp_mfs_sdint.int.cube.psf to tst.sp_mfs_sdint.int.cube.residual
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        image::_setrestoringbeam         Will copy beams from another image to this image
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        BeamManipulator::set        Set image beam set
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        sdintimaging::::casa        Combined image setup ....
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        sdintimaging::::casa        Verifying Input Parameters
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImagerVi2::selectData         MS : papersky_standard.ms | [Opened in readonly mode]
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImagerVi2::selectData           NRows selected : 6318
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.sp_mfs_sdint.joint.multiterm] :
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [tst.sp_mfs_sdint.joint.multiterm] :
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImagerVi2::defineImage         Impars: start
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImagerVi2::defineImage         Shape: [800, 800, 1, 1] Spectral: [1.5e+09] at [0] with increment [1.50017e+09]
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [tst.sp_mfs_sdint.joint.multiterm] with ftmachine : gridft
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.2266 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Natural weighting
06-Dec-2025 01:29:36 2025-12-06 01:29:35        INFO        SynthesisImager::makePSF         ----------------------------------------------------------- Make PSF ---------------------------------------------
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Hessian Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStoreMultiTerm::calcSensitivity +        [37908, -4.18539
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStoreMultiTerm::calcSensitivity +         -4.18539, 2807.38]
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStoreMultiTerm::calcSensitivity         Multi-Term Covariance Matrix : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStoreMultiTerm::calcSensitivity +        [2.63797e-05, 3.93281e-08
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStoreMultiTerm::calcSensitivity +         3.93281e-08, 0.000356204]
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst.sp_mfs_sdint.joint.multiterm][Taylor0] Theoretical sensitivity (Jy/bm):0.00513611
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStoreMultiTerm::calcSensitivity         [tst.sp_mfs_sdint.joint.multiterm][Taylor1] Theoretical sensitivity (Jy/bm):0.0188734
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749917 GHz -> 2.25008GHz.Fractional Bandwidth : 100.011 %.Reference Frequency for Taylor Expansion : 1.5GHz.
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.015344
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SIImageStore::printBeamSet         Beam : 53.662 arcsec, 53.2099 arcsec, 22.4308 deg
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SynthesisImagerVi2::makePrimaryBeam         vi2 : Evaluating Primary Beam model onto image grid(s)
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [tst.sp_mfs_sdint.joint.multiterm] : mtmfs
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        sdintimaging::::casa        SD cube setup ....
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        sdintimaging::::casa        Initializing SDINT Helper instance with tmp file tag 766881
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        sdintimaging::::casa         The center of psf coincides with int psf: (diffRA,diffDec)=( 0.0, 0.0)
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:29:36 2025-12-06 01:29:36        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:29:36 2025-12-06 01:29:36        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SubImageFactory::createImage        Creating image 'tst.sp_mfs_sdint.sd.cube.residual' of shape [800, 800, 1, 3]
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:29:36 2025-12-06 01:29:36        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:29:36 2025-12-06 01:29:36        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:36 2025-12-06 01:29:36        INFO        SubImageFactory::createImage        Creating image 'tst.sp_mfs_sdint.sd.cube.image' of shape [800, 800, 1, 3]
06-Dec-2025 01:29:36 2025-12-06 01:29:37        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:29:36 2025-12-06 01:29:37        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        SubImageFactory::createImage        Creating image 'tst.sp_mfs_sdint.sd.cube.psf' of shape [800, 800, 1, 3]
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        sdintimaging::::casa        Deleting tmp_766881_*plane
06-Dec-2025 01:29:38 2025-12-06 01:29:37        WARN        sdintimaging::task_sdintimaging::casa        The cube for the major cycle has only 3 channels. A minimum of nterms = 2 channels is required for an accurate polynomial fit, but where possible at least 5 to 10 channels that span the frequency range are prefered in order to properly encode frequency dependent intensity and weights.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        sdintimaging::::casa        Feathering INT and SD residual images...
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        imager::feather()        
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        imager::feather()        
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:38 2025-12-06 01:29:37        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:38 2025-12-06 01:29:37        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord+            [0, 0, 0, 2] to [799, 799, 0, 2]  (20:04:40.577, +39.43.34.655, I, 1.99978e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.99978e+09Hz)
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord+            [0, 0, 0, 2] to [799, 799, 0, 2]  (20:04:40.577, +39.43.34.655, I, 1.99978e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.99978e+09Hz)
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        imager::feather()        
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:38 2025-12-06 01:29:38        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:38 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        imager::feather()        
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:40 2025-12-06 01:29:38        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:40 2025-12-06 01:29:38        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        imager::feather()        
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:40 2025-12-06 01:29:39        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord+            [0, 0, 0, 2] to [799, 799, 0, 2]  (20:04:40.577, +39.43.34.655, I, 1.99978e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.99978e+09Hz)
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord+            [0, 0, 0, 2] to [799, 799, 0, 2]  (20:04:40.577, +39.43.34.655, I, 1.99978e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.99978e+09Hz)
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        imager::feather()        
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:40 2025-12-06 01:29:39        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:40 2025-12-06 01:29:39        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SynthesisUtilMethods::fitPsfBeam        Fitting PSF beam for Imagestore : tst.sp_mfs_sdint.joint.cube
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.043371
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SIImageStore::printBeamSet         Restoring Beams
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SIImageStore::printBeamSet +        Chan    Freq   Vel Pol
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SIImageStore::printBeamSet +           0 1.0e+09    33   I   77.5193 arcsec x   76.6073 arcsec pa= 11.1113 deg
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SIImageStore::printBeamSet +           1 1.5e+09 -149846   I   51.6676 arcsec x   51.0710 arcsec pa= 11.3343 deg
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SIImageStore::printBeamSet +           2 2.0e+09 -299726   I   38.7687 arcsec x   38.3203 arcsec pa= 14.0803 deg
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        sdintimaging::::casa        Calculate spectral PSFs and Taylor Residuals...
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SynthesisUtilMethods::fitPsfBeam        Fitting PSF beam for Imagestore : tst.sp_mfs_sdint.joint.multiterm
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.01773
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SIImageStore::printBeamSet         Beam : 53.1832 arcsec, 52.7084 arcsec, 20.2963 deg
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0, over full image : 1.1314
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        SynthesisDeconvolver::setupMask         [tst.sp_mfs_sdint.joint.multiterm] Setting up a mask from [papersky_standard.true.im.masklist]
06-Dec-2025 01:29:40 2025-12-06 01:29:40        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO                You've specified explicitly that the spectral axis should be regridded. However, the input image has a degenerate spectral axis and so it cannot be regridded. Instead, the resulting single output channel will be replicated 1 times in the output image.
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
06-Dec-2025 01:29:47 2025-12-06 01:29:40        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        RegionTextParser::_determineVersion        RegionTextParser::_determineVersion: Found spec version 0
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        AsciiRegionFileParser::_parse        Combined 5 image regions (which excludes any annotation regions)
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        SynthesisDeconvolver::setupMask         ----------------------------------------------------------------------------------------------------------------------------------------
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        SynthesisDeconvolver::setupMask         [tst.sp_mfs_sdint.joint.multiterm] Number of pixels in the clean mask : 44220 out of a total of 640000 pixels. [ 6.90937 % ]
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.1314, over full image : 1.1314
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        sdintimaging::::casa        Minor Cycle controls : {'cycleniter': 20, 'cyclethreshold': 0.1363893300294876, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdint.joint.multiterm] Run mtmfs minor-cycle | CycleThreshold=0.136389, CycleNiter=20, Gain=0.1
06-Dec-2025 01:29:47 2025-12-06 01:29:40        INFO        MultiTermMatrixCleaner::verifyScaleSizes()         Scale sizes to be used for deconvolution : [0, 12, 20, 40, 60, 80, 100]
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 1 = 0 pixels with bias = 1
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 2 = 12 pixels with bias = 1
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 3 = 20 pixels with bias = 1
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 4 = 40 pixels with bias = 1
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 5 = 60 pixels with bias = 1
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 6 = 80 pixels with bias = 1
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MultiTermMatrixCleaner::setupScaleFunctions         scale 7 = 100 pixels with bias = 1
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MultiTermMatrixCleaner::initialise()         Using a PSF patch of 800 pixels on each side for minor-cycle updates.
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 0.990617 at [400, 400]
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749917 GHz -> 2.25008GHz.Fractional Bandwidth : 100.011 %.Reference Frequency for Taylor Expansion : 1.5GHz.
06-Dec-2025 01:29:47 2025-12-06 01:29:41        INFO        MultiTermMatrixCleaner::computeHessianPeak         Calculating PSF and Scale convolutions
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 0 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.990617, -0.000119891
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.000119891, 0.073365]
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 12 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.261247, -0.0234538
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.0234538, 0.0201623]
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 20 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.137015, -0.0147553
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.0147553, 0.0106943]
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 40 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.0438432, -0.00670719
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00670719, 0.00355362]
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 60 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.0181479, -0.00334112
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00334112, 0.00153626]
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 80 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.00861385, -0.00173856
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.00173856, 0.000747211]
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak         The Matrix [H] for 100 pixel scale is : Axis Lengths: [2, 2]  (NB: Matrix in Row/Column order)
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +        [0.00459695, -0.000958446
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::computeHessianPeak +         -0.000958446, 0.00040842]
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
06-Dec-2025 01:29:47 2025-12-06 01:29:42        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 1.14211
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO                [20] Res: 1.06781 Max: 1.29809 Gain: 0.1 Pos: [297, 423] Scale: 100 Coeffs: 18.4318  8.24836 
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 0.114076  [12]: 0  [20]: 0  [40]: 0  [60]: 0  [80]: 0  [100]: 58.914 (in this run)
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 59.0281  [1]: 30.2788
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdint.joint.multiterm] iters=0->20 [20], model=0->59.0275, peakres=1.1314->1.05779, Reached cycleniter.
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 20 iterations.
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO        task_sdintimaging::image::_setrestoringbeam         Will remove any existing beams from image
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO        task_sdintimaging::::        Deleted restoring beam(s)
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO        task_sdintimaging::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
06-Dec-2025 01:29:47 2025-12-06 01:29:46        INFO        task_sdintimaging::SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:29:47 2025-12-06 01:29:48        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:47 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:47 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:47 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:47 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:47 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:47 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        imager::feather()        
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:53 2025-12-06 01:29:48        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        imager::feather()        
06-Dec-2025 01:29:53 2025-12-06 01:29:48        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:53 2025-12-06 01:29:49        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        ImageRegion::fromRecord+            [0, 0, 0, 2] to [799, 799, 0, 2]  (20:04:40.577, +39.43.34.655, I, 1.99978e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.99978e+09Hz)
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        ImageRegion::fromRecord+            [0, 0, 0, 2] to [799, 799, 0, 2]  (20:04:40.577, +39.43.34.655, I, 1.99978e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.99978e+09Hz)
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        imager::feather()        
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:53 2025-12-06 01:29:49        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        sdintimaging::::casa        getSummary call: fullsummary=False
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 1.05779, over full image : 1.05779
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        sdintimaging::::casa        Minor Cycle controls : {'cycleniter': 20, 'cyclethreshold': 0.12751585245132446, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
06-Dec-2025 01:29:53 2025-12-06 01:29:49        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdint.joint.multiterm] Run mtmfs minor-cycle | CycleThreshold=0.127516, CycleNiter=20, Gain=0.1
06-Dec-2025 01:29:53 2025-12-06 01:29:50        INFO        SIImageStoreMultiTerm::calcFractionalBandwidth         MFS frequency range : 0.749917 GHz -> 2.25008GHz.Fractional Bandwidth : 100.011 %.Reference Frequency for Taylor Expansion : 1.5GHz.
06-Dec-2025 01:29:53 2025-12-06 01:29:50        INFO        MultiTermMatrixCleaner::mtclean()         Calculating convolutions of residual images with scales
06-Dec-2025 01:29:53 2025-12-06 01:29:50        INFO        MultiTermMatrixCleaner::mtclean()         Peak convolved residual (within mask)  : 1.06781
06-Dec-2025 01:29:53 2025-12-06 01:29:54        INFO                [40] Res: 0.739462 Max: 0.633658 Gain: 0.1 Pos: [552, 400] Scale: 0 Coeffs: 0.799742  0.0328288 
06-Dec-2025 01:29:53 2025-12-06 01:29:54        INFO        MultiTermMatrixCleaner::mtclean()         Reached max number of iterations for this minor cycle
06-Dec-2025 01:29:53 2025-12-06 01:29:54        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by scale :  [0]: 1.02602  [12]: 0  [20]: 0  [40]: 0  [60]: 0  [80]: 0  [100]: 73.3076 (in this run)
06-Dec-2025 01:29:53 2025-12-06 01:29:54        INFO        MultiTermMatrixCleaner::mtclean()         Total flux by Taylor coefficient :  [0]: 74.333  [1]: 34.0242
06-Dec-2025 01:29:53 2025-12-06 01:29:54        INFO        SDAlgorithmBase::deconvolve         [tst.sp_mfs_sdint.joint.multiterm] iters=0->20 [20], model=59.0284->74.3311, peakres=1.05779->0.732523, Reached cycleniter.
06-Dec-2025 01:29:53 2025-12-06 01:29:54        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 40 iterations.
06-Dec-2025 01:29:57 2025-12-06 01:29:54        INFO        task_sdintimaging::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 3 -------------------------------------
06-Dec-2025 01:29:57 2025-12-06 01:29:54        INFO        task_sdintimaging::SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.4079 GB. Available mem.: 385.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 3. Processes on node: 4.
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [799, 799, 0, 0]  (20:04:40.577, +39.43.34.655, I, 999889040.02Hz to 19:54:07.715, +41.43.25.344, I, 999889040.02Hz)
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        imager::feather()        
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:57 2025-12-06 01:29:55        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:57 2025-12-06 01:29:55        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord+            [0, 0, 0, 1] to [799, 799, 0, 1]  (20:04:40.577, +39.43.34.655, I, 1.49983e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.49983e+09Hz)
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        imager::feather()        
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:57 2025-12-06 01:29:56        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord+            [0, 0, 0, 2] to [799, 799, 0, 2]  (20:04:40.577, +39.43.34.655, I, 1.99978e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.99978e+09Hz)
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_sdplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        Using channel range(s)
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord        Selected bounding box :
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord+            [0, 0, 0, 2] to [799, 799, 0, 2]  (20:04:40.577, +39.43.34.655, I, 1.99978e+09Hz to 19:54:07.715, +41.43.25.344, I, 1.99978e+09Hz)
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        ImageRegion::fromRecord        Creating image 'tmp_477982_intplane' of shape [800, 800, 1, 1]
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        sdintimaging::::casa        start Feathering.....
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        Imager::setvp()         Setting voltage pattern parameters
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        Imager::setvp()         Sky position tolerance is 180 degrees
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        Imager::setvp()         Using system default voltage patterns for each telescope
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        imager::setsdoptions()         Setting single dish processing options
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        imager::feather()        
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        imager::feather()+        Feathering together high and low resolution images...
06-Dec-2025 01:29:57 2025-12-06 01:29:56        INFO        imager::feather()        Each input image has a single restoring beam.
06-Dec-2025 01:29:57 2025-12-06 01:29:56        WARN        ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
06-Dec-2025 01:29:57 2025-12-06 01:29:57        INFO        sdintimaging::::casa        getSummary call: fullsummary=False
06-Dec-2025 01:29:57 2025-12-06 01:29:57        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.732524, over full image : 0.732524
06-Dec-2025 01:29:57 2025-12-06 01:29:57        INFO        sdintimaging::::casa        Reached global stopping criterion : reached nmajor
06-Dec-2025 01:29:57 2025-12-06 01:29:57        INFO        MultiTermMatrixCleaner::computeprincipalsolution()         MTMC :: Computing principal solution on residuals
06-Dec-2025 01:29:57 2025-12-06 01:29:57        INFO        SDAlgorithmBase::restore         [tst.sp_mfs_sdint.joint.multiterm] : Restoring model image.
06-Dec-2025 01:29:57 2025-12-06 01:29:57        INFO        SIImageStore::restore         Beam for chan : 0 : 53.1832 arcsec, 52.7084 arcsec, 20.2963 deg
06-Dec-2025 01:29:57 2025-12-06 01:29:57        INFO        SIImageStore::restore         Beam for chan : 0 : 53.1832 arcsec, 52.7084 arcsec, 20.2963 deg
06-Dec-2025 01:30:14 2025-12-06 01:29:57        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Calculating spectral parameters for Intensity > peakresidual/10 = 0.0739367 Jy/beam
06-Dec-2025 01:30:14 2025-12-06 01:29:57        INFO        SIImageStoreMultiTerm::calculateAlphaBeta         Written Spectral Index Error Image : /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.alpha.error
06-Dec-2025 01:30:14 2025-12-06 01:29:57        INFO        SDAlgorithmMSMFS::restore         Restored images :
06-Dec-2025 01:30:14 2025-12-06 01:29:57        INFO        SDAlgorithmMSMFS::restore         /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.image.tt0  (model=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.model.tt0)
06-Dec-2025 01:30:14 2025-12-06 01:29:57        INFO        SDAlgorithmMSMFS::restore         /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.image.tt1  (model=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228/work/test_task_sdintimaging/tst.sp_mfs_sdint.joint.multiterm.model.tt1)
06-Dec-2025 01:30:14 2025-12-06 01:29:57        INFO        SDAlgorithmMSMFS::restore         
06-Dec-2025 01:30:14 master 1 init 1
06-Dec-2025 01:30:14 2025-12-06 01:29:57        INFO        sdintimaging::::casa        Deleting tmp_477982_*plane
06-Dec-2025 01:30:14 2025-12-06 01:29:57        INFO        sdintimaging::::casa        Searching for images with prefix 'tst.sp_mfs_sdint'... Found these, writing history into them: ['tst.sp_mfs_sdint.int.cube.pb', 'tst.sp_mfs_sdint.int.cube.psf', 'tst.sp_mfs_sdint.int.cube.sumwt', 'tst.sp_mfs_sdint.int.cube.residual', 'tst.sp_mfs_sdint.joint.multiterm.psf.tt0', 'tst.sp_mfs_sdint.joint.multiterm.sumwt.tt0', 'tst.sp_mfs_sdint.joint.multiterm.psf.tt1', 'tst.sp_mfs_sdint.joint.multiterm.sumwt.tt1', 'tst.sp_mfs_sdint.joint.multiterm.psf.tt2', 'tst.sp_mfs_sdint.joint.multiterm.sumwt.tt2', 'tst.sp_mfs_sdint.joint.multiterm.pb.tt0', 'tst.sp_mfs_sdint.joint.multiterm.residual.tt0', 'tst.sp_mfs_sdint.joint.multiterm.residual.tt1', 'tst.sp_mfs_sdint.sd.cube.residual', 'tst.sp_mfs_sdint.sd.cube.image', 'tst.sp_mfs_sdint.sd.cube.psf', 'tst.sp_mfs_sdint.joint.cube.psf', 'tst.sp_mfs_sdint.joint.multiterm.mask', 'tst.sp_mfs_sdint.joint.multiterm.model.tt0', 'tst.sp_mfs_sdint.joint.multiterm.model.tt1', 'tst.sp_mfs_sdint.int.cube.model', 'tst.sp_mfs_sdint.sd.cube.model', 'tst.sp_mfs_sdint.joint.cube.residual', 'tst.sp_mfs_sdint.joint.multiterm.image.tt0', 'tst.sp_mfs_sdint.joint.multiterm.image.tt1', 'tst.sp_mfs_sdint.joint.multiterm.alpha', 'tst.sp_mfs_sdint.joint.multiterm.alpha.error']
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        sdintimaging::::casa        Task sdintimaging complete. Start time: 2025-12-05 20:29:33.151271 End time: 2025-12-05 20:29:58.095653
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        sdintimaging::::casa        ##### End Task: sdintimaging         #####
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        sdintimaging::::casa        ##########################################
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        name::        No table opened.
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        showcache::        The Table Cache is empty.
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        sdintimaging::::casa        [ check_ret_structure ] Return dictionary struture check: type=reduced ( Pass : found all expected keys  )
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        sdintimaging::::casa+        [ check_val ] iterdone is 40 ( Pass : should be 40, Exact: True )
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        sdintimaging::::casa+        [ check_val ] nmajordone is 3 ( Pass : should be 3, Exact: True )
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        sdintimaging::::casa+        [ check_ims ] Image made : ['tst.sp_mfs_sdint.joint.multiterm.psf.tt0', 'tst.sp_mfs_sdint.joint.multiterm.residual.tt0', 'tst.sp_mfs_sdint.joint.multiterm.image.tt0', 'tst.sp_mfs_sdint.joint.multiterm.image.tt1', 'tst.sp_mfs_sdint.joint.multiterm.alpha'] =  [True, True, True, True, True] ( Pass : should all be True )
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        sdintimaging::::casa+        All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
06-Dec-2025 01:30:14 2025-12-06 01:29:58        INFO        sdintimaging::::casa+        [check_history] All expected history entries found. (Pass)
06-Dec-2025 01:30:14 .
06-Dec-2025 01:30:14 ----------------------------------------------------------------------
06-Dec-2025 01:30:14 Ran 21 tests in 1739.413s
06-Dec-2025 01:30:14
06-Dec-2025 01:30:14 OK (skipped=2)
06-Dec-2025 01:30:14 Marking test as passed.
06-Dec-2025 01:30:14 Executor result: 0
06-Dec-2025 01:30:14 Failed tests:[]
06-Dec-2025 01:30:14
06-Dec-2025 01:30:14 Results: [{'testname': 'test_regression_alma_M100_if', 'runtime': '0:18:25.791678', 'returncode': 0, 'testerr': []}, {'testname': 'test_stk_srdp_restore_mode', 'runtime': '0:39:27.700491', 'returncode': 0, 'testerr': []}, {'testname': 'test_stk_alma_pipeline_imaging', 'runtime': '1:37:29.905652', 'returncode': 0, 'testerr': []}, {'testname': 'test_stk_vlass_pipeline_imaging', 'runtime': '0:39:43.530516', 'returncode': 0, 'testerr': []}, {'testname': 'test_stk_vla_users_continuum_from_SDM', 'runtime': '0:19:50.551791', 'returncode': 0, 'testerr': []}, {'testname': 'test_task_deconvolve', 'runtime': '0:28:37.098430', 'returncode': 0, 'testerr': []}, {'testname': 'test_task_tclean', 'runtime': '3:56:46.818292', 'returncode': 0, 'testerr': []}, {'testname': 'test_task_sdintimaging', 'runtime': '0:29:19.297025', 'returncode': 0, 'testerr': []}]
06-Dec-2025 01:30:14 Stopping Xvfb.
06-Dec-2025 01:30:14 Stopping framebuffer <subprocess.Popen object at 0x7fca44726940>
06-Dec-2025 01:30:14 Virtual frame buffer deployed at :3228257 with pid 3228546 successfully shutdown
06-Dec-2025 01:30:14 Xvfb stopped.
06-Dec-2025 01:30:14 Finished task 'Test' with result: Success
06-Dec-2025 01:30:14 Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
06-Dec-2025 01:30:14 Parsing test results under /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31215-CCM228...
06-Dec-2025 01:30:14 Finished task 'Parse test results' with result: Success
06-Dec-2025 01:30:14 Running post build plugin 'Docker Container Cleanup'
06-Dec-2025 01:30:14 Running post build plugin 'NCover Results Collector'
06-Dec-2025 01:30:14 Running post build plugin 'Build Results Label Collector'
06-Dec-2025 01:30:14 Running post build plugin 'Clover Results Collector'
06-Dec-2025 01:30:14 Running post build plugin 'npm Cache Cleanup'
06-Dec-2025 01:30:14 Running post build plugin 'Artifact Copier'
06-Dec-2025 01:30:14 Finalising the build...
06-Dec-2025 01:30:14 Stopping timer.
06-Dec-2025 01:30:14 Build CASASPECS-DEVVERCASALITHPY31215-CCM228-1 completed.
05-Dec-2025 20:30:15 Running on server: post build plugin 'Build Hanging Detection Configuration'
05-Dec-2025 20:30:15 Running on server: post build plugin 'NCover Results Collector'
05-Dec-2025 20:30:15 Running on server: post build plugin 'Build Labeller'
05-Dec-2025 20:30:15 Running on server: post build plugin 'Clover Delta Calculator'
05-Dec-2025 20:30:15 Running on server: post build plugin 'Maven Dependencies Postprocessor'
05-Dec-2025 20:30:15 All post build plugins have finished
05-Dec-2025 20:30:15 Generating build results summary...
05-Dec-2025 20:30:15 Saving build results to disk...
05-Dec-2025 20:30:15 Store variable context...
05-Dec-2025 20:30:15 Finished building CASASPECS-DEVVERCASALITHPY31215-CCM228-1.