Build log

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

20-Oct-2026 13:32:34	2026-10-20 13:32:03 INFO applycal::AgentFlagger::open Table type is Measurement Set
20-Oct-2026 13:32:34	2026-10-20 13:32:03 INFO FlagVersion::saveFlagVersion Creating new backup flag file called applycal_2
20-Oct-2026 13:32:34	2026-10-20 13:32:05 INFO applycal::::casa Task applycal complete. Start time: 2026-10-20 13:32:03.186544 End time: 2026-10-20 13:32:04.747949
20-Oct-2026 13:32:34	2026-10-20 13:32:05 INFO applycal::::casa ##### End Task: applycal #####
20-Oct-2026 13:32:34	2026-10-20 13:32:05 INFO applycal::::casa ##########################################
20-Oct-2026 13:32:34	2026-10-20 13:32:05 INFO applycal::::casa ##########################################
20-Oct-2026 13:32:34	2026-10-20 13:32:05 INFO applycal::::casa ##### Begin Task: applycal #####
20-Oct-2026 13:32:34	2026-10-20 13:32:05 INFO applycal::::casa applycal( vis='3c391_ctm_mosaic_10s_spw0.ms', field='2~8', spw='', intent='', selectdata=True, timerange='', uvrange='', antenna='', scan='', observation='', msselect='', docallib=False, callib='', gaintable=['3c391_ctm_mosaic_10s_spw0.antpos', '3c391_ctm_mosaic_10s_spw0.fluxscale1', '3c391_ctm_mosaic_10s_spw0.K0', '3c391_ctm_mosaic_10s_spw0.B0'], gainfield=['', 'J1822-0938', '', ''], interp=['', 'linear', '', ''], spwmap=[], calwt=[False], parang=False, applymode='', flagbackup=True )
20-Oct-2026 13:32:34	2026-10-20 13:32:05 INFO applycal::AgentFlagger::open Table type is Measurement Set
20-Oct-2026 13:32:34	2026-10-20 13:32:05 INFO FlagVersion::saveFlagVersion Creating new backup flag file called applycal_3
20-Oct-2026 13:32:34	2026-10-20 13:32:09 INFO applycal::::casa Task applycal complete. Start time: 2026-10-20 13:32:04.749184 End time: 2026-10-20 13:32:09.117044
20-Oct-2026 13:32:34	2026-10-20 13:32:09 INFO applycal::::casa ##### End Task: applycal #####
20-Oct-2026 13:32:34	2026-10-20 13:32:09 INFO applycal::::casa ##########################################
20-Oct-2026 13:32:34	2026-10-20 13:32:09 INFO split::::casa ##########################################
20-Oct-2026 13:32:34	2026-10-20 13:32:09 INFO split::::casa ##### Begin Task: split #####
20-Oct-2026 13:32:34	2026-10-20 13:32:09 INFO split::::casa split( vis='3c391_ctm_mosaic_10s_spw0.ms', outputvis='3c391_ctm_mosaic_spw0.ms', keepmms=True, field='2~8', spw='', scan='', antenna='', correlation='RR,LL', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', keepflags=True, width=1, timebin='0s', combine='' )
20-Oct-2026 13:32:34	2026-10-20 13:32:09 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
20-Oct-2026 13:32:34	2026-10-20 13:32:09 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO ParallelDataHelper::::casa Finalizing MMS structure
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO ms::createmultims Copying subtables from /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_mosaic_spw0.ms.data/3c391_ctm_mosaic_spw0.ms.0000.ms to the other MMS members.
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO split::::casa Task split complete. Start time: 2026-10-20 13:32:09.119905 End time: 2026-10-20 13:32:12.374312
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO split::::casa ##### End Task: split #####
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO split::::casa ##########################################
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO statwt::::casa ##########################################
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO statwt::::casa ##### Begin Task: statwt #####
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO statwt::::casa statwt( vis='3c391_ctm_mosaic_spw0.ms', selectdata=True, field='', spw='', intent='', array='', observation='', scan='', combine='', timebin=1, slidetimebin=False, chanbin='spw', minsamp=2, statalg='classic', fence=-1.0, center='mean', lside=True, zscore=-1.0, maxiter=-1, fitspw='', excludechans=False, wtrange=[], flagbackup=True, preview=False, datacolumn='data' )
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO statwt::::casa Backup original flags before applying new flags
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO AgentFlagger::open Table type is Measurement Set
20-Oct-2026 13:32:34	2026-10-20 13:32:12 INFO FlagVersion::saveFlagVersion Creating new backup flag file called statwt_1
20-Oct-2026 13:32:34	2026-10-20 13:32:13 INFO StatWtColConfig::_determineFlags CORRECTED_DATA is not present. Updating the SIGMA/SIGMA_SPECTRUM and WEIGHT/WEIGHT_SPECTRUM values based on calculations using the DATA column.
20-Oct-2026 13:32:34	2026-10-20 13:32:13 INFO StatWtTVI::StatWtTVI Using DATA to compute weights
20-Oct-2026 13:32:34	....10....20....30....40....50....60....70....80....90....100%
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO StatWtTVI::summarizeFlagging Originally, 35.0215% of the data were flagged. StatWtTVI flagged an additional 0%.
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO StatWtTVI::summarizeFlagging TOTAL FLAGGED DATA AFTER RUNNING STATWT: 35.0215%
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO StatWtTVI::summarizeFlagging
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO StatWtTVI::summarizeFlagging SPECTRAL_WINDOW SAMPLES_WITH_NON-ZERO_VARIANCE SAMPLES_WHERE_REAL_PART_VARIANCE_DIFFERS_BY_>50%_FROM_IMAGINARY_PART
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO StatWtTVI::summarizeFlagging 0 997366 260704
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO StatWtTVI::_logUsedChannels Weights are being computed using SPW 0, channels 0~63
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO StatWtTVI::summarizeStats The mean of the computed weights is 321.368
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO StatWtTVI::summarizeStats The variance of the computed weights is 348637
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO StatWtTVI::summarizeStats Weights which had corresponding flags of True prior to running this application were not used to compute these stats.
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO statwt::::casa Task statwt complete. Start time: 2026-10-20 13:32:12.412524 End time: 2026-10-20 13:32:34.790103
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO statwt::::casa ##### End Task: statwt #####
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO statwt::::casa ##########################################
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO tclean::::casa ##########################################
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO tclean::::casa ##### Begin Task: tclean #####
20-Oct-2026 13:32:34	2026-10-20 13:32:35 INFO tclean::::casa tclean( vis='3c391_ctm_mosaic_spw0.ms', selectdata=True, field='', spw='', timerange='', uvrange='', antenna='', scan='', observation='', intent='', datacolumn='corrected', imagename='3c391_ctm_spw0_multiscale', imsize=[480, 480], cell=['2.5arcsec', '2.5arcsec'], phasecenter='', stokes='I', projection='SIN', startmodel='', specmode='mfs', reffreq='', nchan=-1, start='', width='', outframe='LSRK', veltype='radio', restfreq=[], interpolation='linear', perchanweightdensity=True, gridder='mosaic', facets=1, psfphasecenter='', wprojplanes=1, vptable='', mosweight=True, aterm=True, psterm=False, wbawp=True, conjbeams=False, cfcache='', usepointing=False, computepastep=360.0, rotatepastep=360.0, pointingoffsetsigdev=[], pblimit=0.2, normtype='flatnoise', deconvolver='multiscale', scales=[0, 5, 15, 45], nterms=2, smallscalebias=0.9, fusedthreshold=0.0, largestscale=-1, restoration=True, restoringbeam=[], pbcor=False, outlierfile='', weighting='briggs', robust=0.5, noise='1.0Jy', npixels=0, fracbw=-1.0, uvtaper=[''], niter=20000, gain=0.1, threshold='1.0mJy', nsigma=0.0, cycleniter=-1, cyclefactor=1.0, minpsffraction=0.05, maxpsffraction=0.8, interactive=False, nmajor=-1, fullsummary=False, usemask='user', mask='3c391_clean_mask.crtf', pbmask=0.0, sidelobethreshold=3.0, noisethreshold=5.0, lownoisethreshold=1.5, negativethreshold=0.0, smoothfactor=1.0, minbeamfrac=0.3, cutthreshold=0.01, growiterations=75, dogrowprune=True, minpercentchange=-1.0, verbose=False, fastnoise=True, restart=True, savemodel='none', calcres=True, calcpsf=True, psfcutoff=0.35, parallel=True )
20-Oct-2026 13:33:26	2026-10-20 13:32:35 INFO tclean::::casa Verifying Input Parameters
20-Oct-2026 13:33:26	2026-10-20 13:32:36 INFO tclean::::casa setupCluster, Setting up 7 engines.
20-Oct-2026 13:33:26	2026-10-20 13:32:36 INFO SynthesisUtilMethods::continuumDataPartition
20-Oct-2026 13:33:26	2026-10-20 13:32:36 INFO SynthesisUtilMethods::continuumDataPartition + Rows = 657829 767459 [P][M]: 0:6 ROWNUMBER() >= 657829 && ROWNUMBER() <= 767459
20-Oct-2026 13:33:26	2026-10-20 13:32:36 INFO tclean::::casa Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': '3c391_ctm_mosaic_spw0.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 109638', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '1': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': '3c391_ctm_mosaic_spw0.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 109639 && ROWNUMBER() <= 219276', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '2': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': '3c391_ctm_mosaic_spw0.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 219277 && ROWNUMBER() <= 328914', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '3': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': '3c391_ctm_mosaic_spw0.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 328915 && ROWNUMBER() <= 438552', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '4': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': '3c391_ctm_mosaic_spw0.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 438553 && ROWNUMBER() <= 548190', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '5': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': '3c391_ctm_mosaic_spw0.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 548191 && ROWNUMBER() <= 657828', 'timestr': '', 'usescratch': False, 'uvdist': ''}}, '6': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': '3c391_ctm_mosaic_spw0.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 657829 && ROWNUMBER() <= 767459', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
20-Oct-2026 13:33:26	2026-10-20 13:32:36 INFO SynthesisImagerVi2::selectData MS : 3c391_ctm_mosaic_spw0.ms \| [Opened in readonly mode]
20-Oct-2026 13:33:26	2026-10-20 13:32:36 INFO SynthesisImagerVi2::selectData NRows selected : 767459
20-Oct-2026 13:33:26	2026-10-20 13:32:38 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
20-Oct-2026 13:33:26	2026-10-20 13:32:38 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
20-Oct-2026 13:33:26	2026-10-20 13:32:39 INFO SynthesisImagerVi2::defineImage Impars: start
20-Oct-2026 13:33:26	2026-10-20 13:32:39 INFO SynthesisImagerVi2::defineImage Shape: [480, 480, 1, 1] Spectral: [4.59835e+09] at [0] with increment [1.27992e+08]
20-Oct-2026 13:33:26	2026-10-20 13:32:39 INFO SynthesisImagerVi2::defineImage Set Gridding options for [3c391_ctm_spw0_multiscale] with ftmachine : mosaicft
20-Oct-2026 13:33:26	2026-10-20 13:32:39 INFO SynthesisImager::getVPRecord Using default Voltage Patterns from the VPManager
20-Oct-2026 13:33:26	2026-10-20 13:32:39 INFO VPSkyJones::VPSkyJones PB used EVLA
20-Oct-2026 13:33:26	2026-10-20 13:32:39 INFO SynthesisImagerVi2::nSubCubeFitInMemory Required memory: 0.2373 GB. Available mem.: 390.7 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 8.
20-Oct-2026 13:33:26	2026-10-20 13:32:39 INFO SynthesisImagerVi2::weight() Set imaging weights : Briggs weighting: sidelobes will be suppressed over full image
20-Oct-2026 13:33:26	2026-10-20 13:32:47 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:32:47 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:32:47 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:32:47 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:32:47 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:32:47 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:32:47 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:32:47 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:33:26	2026-10-20 13:32:48 INFO tclean::::casa ...done
20-Oct-2026 13:33:26	2026-10-20 13:32:48 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:33:26	2026-10-20 13:32:49 INFO tclean::::casa ...done
20-Oct-2026 13:33:26	2026-10-20 13:32:52 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:33:26	2026-10-20 13:32:53 INFO tclean::::casa ...done
20-Oct-2026 13:33:26	2026-10-20 13:32:59 INFO SynthesisImagerVi2::selectData MS : 3c391_ctm_mosaic_spw0.ms \| [Opened in readonly mode]
20-Oct-2026 13:33:26	2026-10-20 13:32:59 INFO SynthesisImagerVi2::selectData NRows selected : 767459
20-Oct-2026 13:33:26	2026-10-20 13:33:00 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
20-Oct-2026 13:33:26	2026-10-20 13:33:00 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
20-Oct-2026 13:33:26	2026-10-20 13:33:01 INFO SynthesisImagerVi2::defineImage Impars: start
20-Oct-2026 13:33:26	2026-10-20 13:33:01 INFO SynthesisImagerVi2::defineImage Shape: [480, 480, 1, 1] Spectral: [4.59835e+09] at [0] with increment [1.27992e+08]
20-Oct-2026 13:33:26	2026-10-20 13:33:01 INFO SynthesisImagerVi2::defineImage Set Gridding options for [3c391_ctm_spw0_multiscale] with ftmachine : mosaicft
20-Oct-2026 13:33:26	2026-10-20 13:33:01 INFO SynthesisImager::getVPRecord Using default Voltage Patterns from the VPManager
20-Oct-2026 13:33:26	2026-10-20 13:33:01 INFO VPSkyJones::VPSkyJones PB used EVLA
20-Oct-2026 13:33:26	2026-10-20 13:33:01 INFO SynthesisImagerVi2::nSubCubeFitInMemory Required memory: 0.2373 GB. Available mem.: 390.7 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 8.
20-Oct-2026 13:33:26	2026-10-20 13:33:01 INFO SynthesisImagerVi2::weight() Set imaging weights : Briggs weighting: sidelobes will be suppressed over full image
20-Oct-2026 13:33:26	2026-10-20 13:33:10 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:33:10 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:33:10 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:33:10 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:33:10 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:33:10 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:33:10 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
20-Oct-2026 13:33:26	2026-10-20 13:33:10 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:33:26	2026-10-20 13:33:11 INFO tclean::::casa ...done
20-Oct-2026 13:33:26	2026-10-20 13:33:11 INFO task_tclean::SynthesisDeconvolver::setupDeconvolution Set Deconvolution Options for [3c391_ctm_spw0_multiscale] : multiscale
20-Oct-2026 13:33:26	2026-10-20 13:33:11 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:33:26	2026-10-20 13:33:12 INFO tclean::::casa ...done
20-Oct-2026 13:33:26	2026-10-20 13:33:12 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:33:26
20-Oct-2026 13:33:26	0%....10....20....30....40....50
20-Oct-2026 13:33:26	0%....10....20....30....40
20-Oct-2026 13:33:26	0%....10....20....30....40
20-Oct-2026 13:33:26	0%....10....20....30....40....50
20-Oct-2026 13:33:26	0%....10....20....30....40.
20-Oct-2026 13:33:26	0%....10....20....30....40....50.
20-Oct-2026 13:33:26	0%....10....20....30....40....50........................6050.605060.60........50.............70...60..70...607070............60.........80.70.80..80.80...70.........70..........80.90...90...9090........100%
20-Oct-2026 13:33:26	..80...100%
20-Oct-2026 13:33:26	80...100%
20-Oct-2026 13:33:26	.....100%
20-Oct-2026 13:33:26	90........100%
20-Oct-2026 13:33:26	..90..90....100%
20-Oct-2026 13:33:26	...100%
20-Oct-2026 13:33:26	2026-10-20 13:33:26 INFO tclean::::casa ...done
20-Oct-2026 13:33:39	2026-10-20 13:33:26 INFO SynthesisNormalizer::gatherImages Gather psf,weight images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:33:39	2026-10-20 13:33:26 INFO SIImageStore::calcSensitivity [3c391_ctm_spw0_multiscale] Theoretical sensitivity (Jy/bm):1.06835e-05
20-Oct-2026 13:33:39	2026-10-20 13:33:26 INFO SIImageStore::getPSFGaussian Time to fit Gaussian to PSF 0.005881
20-Oct-2026 13:33:39	2026-10-20 13:33:26 INFO SIImageStore::printBeamSet Beam : 17.0639 arcsec, 14.9556 arcsec, 21.6316 deg
20-Oct-2026 13:33:39	2026-10-20 13:33:26 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:33:39	2026-10-20 13:33:27 INFO tclean::::casa ...done
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:33:39	2026-10-20 13:33:28 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:33:39
20-Oct-2026 13:33:39	0%....10....20....30....40....50.
20-Oct-2026 13:33:39	0%....10....20....30....40....50....60....70.
20-Oct-2026 13:33:39	0%....10....20....30....40....50....60....70..
20-Oct-2026 13:33:39	0%....10....20....30....40....50....60
20-Oct-2026 13:33:39	0%....10....20....30....40....50....60
20-Oct-2026 13:33:39	0%....10....20....30....40....50....60....70.
20-Oct-2026 13:33:39	0%....10....20....30....40....50....60....70..................8080..8080........6070.70..................909090..90....8070...100%
20-Oct-2026 13:33:39	...100%
20-Oct-2026 13:33:39	......100%
20-Oct-2026 13:33:39	....100%
20-Oct-2026 13:33:39	..80......90.80.....100%
20-Oct-2026 13:33:39	.90.....100%
20-Oct-2026 13:33:39	..90....100%
20-Oct-2026 13:33:39	2026-10-20 13:33:39 INFO tclean::::casa ...done
20-Oct-2026 13:33:39	2026-10-20 13:33:39 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0, over full image : 0.123931
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO SynthesisDeconvolver::setupMask [3c391_ctm_spw0_multiscale] Setting up a mask from [3c391_clean_mask.crtf]
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO CasacRegionManager::fromBCS No directional region specified. Using full positional plane.
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO CasacRegionManager::fromBCS Using all spectral channels.
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO CasacRegionManager::fromBCS Using polarizations ALL
20-Oct-2026 13:33:39	2026-10-20 13:33: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.
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO ImageMaskAttacher::makeMask Created and initialized mask `mask0'
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO RegionTextParser::_determineVersion RegionTextParser::_determineVersion: Found spec version 0
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO AsciiRegionFileParser::_parse Combined 1 image regions (which excludes any annotation regions)
20-Oct-2026 13:33:39	2026-10-20 13:33: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).
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO SynthesisDeconvolver::setupMask ----------------------------------------------------------------------------------------------------------------------------------------
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO SynthesisDeconvolver::setupMask [3c391_ctm_spw0_multiscale] Number of pixels in the clean mask : 19718 out of a total of 230400 pixels. [ 8.55816 % ]
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.123931, over full image : 0.123931
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 20000, 'cyclethreshold': 0.011826693080365658, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.0118267, CycleNiter=20000, Gain=0.1
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:33:39	2026-10-20 13:33:40 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() Initial maximum residual is 0.469931 within the mask
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() 200 0.0711923 2.46328
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() 400 0.0143844 3.3664
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() Reached stopping threshold 0.0118267 at iteration 505
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() Optimum flux is 0.0117667
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() 0 0.0641231
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() 1 0.170624
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() 2 3.44997
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO MatrixCleaner::clean() 3 0.0269819
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->506 [506], model=0->3.71053, peakres=0.123931->0.0117667, Reached cyclethreshold.
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 506 iterations.
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:33:58	2026-10-20 13:33:40 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:33:58	2026-10-20 13:33:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:33:58	2026-10-20 13:33:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:33:58	2026-10-20 13:33:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:33:58	2026-10-20 13:33:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:33:58	2026-10-20 13:33:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:33:58	2026-10-20 13:33:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:33:58	2026-10-20 13:33:41 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:33:58	2026-10-20 13:33:41 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:33:58
20-Oct-2026 13:33:58	0%....10....
20-Oct-2026 13:33:58	0%....10....
20-Oct-2026 13:33:58	0%....10....20....30..
20-Oct-2026 13:33:58	0%....10....
20-Oct-2026 13:33:58	0%....10....20....30..
20-Oct-2026 13:33:58	0%....10....20....30.
20-Oct-2026 13:33:58	0%....10....20....30...2020..20......40.40...40..40...........30.....30.....50...50.50.50..30...........40...40.........60.6060...40....60........50....50.....70..70.......70.70.50........60.60........80...80...8080..........60...70.....70....90..90...9090......100%
20-Oct-2026 13:33:58	.....100%
20-Oct-2026 13:33:58	.70.80...100%
20-Oct-2026 13:33:58	.....100%
20-Oct-2026 13:33:58	.....80....9080......100%
20-Oct-2026 13:33:58	...90.....100%
20-Oct-2026 13:33:58	.90....100%
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO tclean::::casa ...done
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.0139507, over full image : 0.0139507
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 19494, 'cyclethreshold': 0.0013313069939613342, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.00133131, CycleNiter=19494, Gain=0.1
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:33:58	2026-10-20 13:33:59 INFO MatrixCleaner::clean() Initial maximum residual is 0.0489674 within the mask
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO MatrixCleaner::clean() Diverging due to large scale?
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO MatrixCleaner::clean() 0 0.00117514
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO MatrixCleaner::clean() 1 0.0082739
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO MatrixCleaner::clean() 2 0.257526
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO MatrixCleaner::clean() 3 0.0260557
20-Oct-2026 13:34:20	2026-10-20 13:33:59 WARN SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185) MSClean minor cycle stopped at large scale negative or diverging
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->74 [74], model=3.71053->3.97751, peakres=0.0139507->0.0111876, Exited multiscale minor cycle without reaching any stopping criterion.
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 580 iterations.
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:34:20	2026-10-20 13:33:59 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:34:20
20-Oct-2026 13:34:20	0%....10....20....30...
20-Oct-2026 13:34:20	0%....10....20
20-Oct-2026 13:34:20	0%....10....20
20-Oct-2026 13:34:20	0%....10....20....30...
20-Oct-2026 13:34:20	0%....10....
20-Oct-2026 13:34:20	0%....10....20....30...
20-Oct-2026 13:34:20	0%....10....20....30..20......4040...40..40..........30.......30...50....5050....50..30........40...40........60...60.60...40....60.......50.50........70...70......7070....50.....60.60.........80..80....8080.............6070...70.......90..90..9090.....100%
20-Oct-2026 13:34:20	......100%
20-Oct-2026 13:34:20	..8070....100%
20-Oct-2026 13:34:20	...100%
20-Oct-2026 13:34:20	.....80.....90..80....100%
20-Oct-2026 13:34:20	..90......100%
20-Oct-2026 13:34:20	.90....100%
20-Oct-2026 13:34:20	2026-10-20 13:34:18 INFO tclean::::casa ...done
20-Oct-2026 13:34:20	2026-10-20 13:34:18 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.0113207, over full image : 0.0113207
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 19420, 'cyclethreshold': 0.0010803299956023693, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.00108033, CycleNiter=19420, Gain=0.1
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() Initial maximum residual is 0.264782 within the mask
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() 200 0.0247378 0.650537
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() 400 0.0193002 1.07716
20-Oct-2026 13:34:20	2026-10-20 13:34:19 INFO MatrixCleaner::clean() 600 0.01604 1.39109
20-Oct-2026 13:34:20	2026-10-20 13:34:20 INFO MatrixCleaner::clean() 800 0.0136018 1.6325
20-Oct-2026 13:34:20	2026-10-20 13:34:20 INFO MatrixCleaner::clean() 1000 0.00400562 1.8299
20-Oct-2026 13:34:20	2026-10-20 13:34:20 INFO MatrixCleaner::clean() 1200 0.00352989 1.99719
20-Oct-2026 13:34:20	2026-10-20 13:34:20 INFO MatrixCleaner::clean() 1400 0.00313125 2.14576
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 1600 0.00822065 2.28115
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 1800 0.0073705 2.39586
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 2000 0.00665978 2.491
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 0 0.133816
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 1 0.164411
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 2 1.90829
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 3 0.284476
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() Failed to reach stopping threshold
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() Initial maximum residual is 0.00226852 within the mask
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() Diverging due to large scale?
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 0 0.000226852
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 1 0
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 2 0.000666034
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO MatrixCleaner::clean() 3 0
20-Oct-2026 13:34:40	2026-10-20 13:34:21 WARN SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185) MSClean minor cycle stopped at large scale negative or diverging
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->2002 [2002], model=3.97751->6.46872, peakres=0.0113207->0.0113212, Possible divergence. Peak residual increased by 10% from minimum.
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 2582 iterations.
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:34:40	2026-10-20 13:34:21 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:34:40
20-Oct-2026 13:34:40	0%....10....20
20-Oct-2026 13:34:40	0%....10....20
20-Oct-2026 13:34:40	0%....10....20
20-Oct-2026 13:34:40	0%....10....20....30..
20-Oct-2026 13:34:40	0%....10....20....30....
20-Oct-2026 13:34:40	0%....10....20....30...
20-Oct-2026 13:34:40	0%....10....20....30.......40..40..40..40.............30...30.....50...50.50...50.30...........4040...........60.60.60...40..60........50...50........70..70....70..70.....50..60...60..........80..80.80..80.............7060...70........90.90.9090.......100%
20-Oct-2026 13:34:40	....100%
20-Oct-2026 13:34:40	...80..70..100%
20-Oct-2026 13:34:40	...100%
20-Oct-2026 13:34:40	.....80.....90..80....100%
20-Oct-2026 13:34:40	..90.....100%
20-Oct-2026 13:34:40	..90....100%
20-Oct-2026 13:34:40	2026-10-20 13:34:40 INFO tclean::::casa ...done
20-Oct-2026 13:34:40	2026-10-20 13:34:40 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:34:40	2026-10-20 13:34:40 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00330019, over full image : 0.00573342
20-Oct-2026 13:34:40	2026-10-20 13:34:40 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 17418, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
20-Oct-2026 13:34:40	2026-10-20 13:34:40 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=17418, Gain=0.1
20-Oct-2026 13:34:40	2026-10-20 13:34:40 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:34:40	2026-10-20 13:34:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:34:40	2026-10-20 13:34:41 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:34:40	2026-10-20 13:34:41 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:34:40	2026-10-20 13:34:41 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:34:40	2026-10-20 13:34:41 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:34:40	2026-10-20 13:34:41 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:34:40	2026-10-20 13:34:41 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:34:40	2026-10-20 13:34:41 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:34:40	2026-10-20 13:34:41 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() Initial maximum residual is 0.0127214 within the mask
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() Diverging due to large scale?
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() 0 0
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() 1 0
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() 2 0.00739823
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO MatrixCleaner::clean() 3 0.00900338
20-Oct-2026 13:34:59	2026-10-20 13:34:41 WARN SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185) MSClean minor cycle stopped at large scale negative or diverging
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->7 [7], model=6.46873->6.47611, peakres=0.00330019->0.00319061, Exited multiscale minor cycle without reaching any stopping criterion.
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 2589 iterations.
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:34:59	2026-10-20 13:34:41 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:34:59
20-Oct-2026 13:34:59	0%....10....20....30....
20-Oct-2026 13:34:59	0%....10....20
20-Oct-2026 13:34:59	0%....10....20....30....40
20-Oct-2026 13:34:59	0%....10....20....30....
20-Oct-2026 13:34:59	0%....10....20.
20-Oct-2026 13:34:59	0%....10....20.
20-Oct-2026 13:34:59	0%....10....20....30....40..40..40...........30.......30...50..50..50...30.50...........40.40..........6060..6040.....60.........50..50........70...70.....7070.50..........6060.........80..80..80.80..........60...70.....70.....90...90.9090.......100%
20-Oct-2026 13:34:59	....100%
20-Oct-2026 13:34:59	.70.80....100%
20-Oct-2026 13:34:59	....100%
20-Oct-2026 13:34:59	.....80....9080.......100%
20-Oct-2026 13:34:59	..90......100%
20-Oct-2026 13:34:59	90....100%
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO tclean::::casa ...done
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00319188, over full image : 0.00573304
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 17411, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=17411, Gain=0.1
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::clean() Initial maximum residual is 0.0902081 within the mask
20-Oct-2026 13:34:59	2026-10-20 13:34:59 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:35:20	2026-10-20 13:34:59 INFO MatrixCleaner::clean() 200 0.00807465 0.25898
20-Oct-2026 13:35:20	2026-10-20 13:35:00 INFO MatrixCleaner::clean() 400 0.00683434 0.425356
20-Oct-2026 13:35:20	2026-10-20 13:35:00 INFO MatrixCleaner::clean() 600 0.00611194 0.55079
20-Oct-2026 13:35:20	2026-10-20 13:35:00 INFO MatrixCleaner::clean() 800 0.00190047 0.649999
20-Oct-2026 13:35:20	2026-10-20 13:35:00 INFO MatrixCleaner::clean() 1000 0.00514406 0.736802
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() 1200 0.0021316 0.812211
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() 1400 0.00442275 0.875158
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() 1600 0.00141233 0.933863
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() 1800 0.00132057 0.989256
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() 2000 0.00164392 1.03837
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() 0 0.0904452
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() 1 0.0555198
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() 2 0.67114
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() 3 0.221262
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() Failed to reach stopping threshold
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() Initial maximum residual is 0.00364809 within the mask
20-Oct-2026 13:35:20	2026-10-20 13:35:01 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO MatrixCleaner::clean() Diverging due to large scale?
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO MatrixCleaner::clean() 0 0.00160586
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO MatrixCleaner::clean() 1 0.000327359
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO MatrixCleaner::clean() 2 0.00617268
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO MatrixCleaner::clean() 3 0.00273267
20-Oct-2026 13:35:20	2026-10-20 13:35:02 WARN SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185) MSClean minor cycle stopped at large scale negative or diverging
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->2039 [2039], model=6.47613->7.52258, peakres=0.00319188->0.00316411, Possible divergence. Peak residual increased by 10% from minimum.
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 4628 iterations.
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:35:20	2026-10-20 13:35:02 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:35:20
20-Oct-2026 13:35:20	0%....10....20....30....40
20-Oct-2026 13:35:20	0%....10....20....30....
20-Oct-2026 13:35:20	0%....10....20
20-Oct-2026 13:35:20	0%....10....20.
20-Oct-2026 13:35:20	0%....10....20.
20-Oct-2026 13:35:20	0%....10....20....30....40
20-Oct-2026 13:35:20	0%....10....20....30....40...40............30......30.....5050..50....50.30..........4040...........60.60..60....40.60.........50.50.........70...70...70.70......50....60.60..........80.80..80..80.............6070..70..........9090.90.90........100%
20-Oct-2026 13:35:20	.....100%
20-Oct-2026 13:35:20	8070....100%
20-Oct-2026 13:35:20	...100%
20-Oct-2026 13:35:20	.....80.....90..80....100%
20-Oct-2026 13:35:20	..90......100%
20-Oct-2026 13:35:20	.90....100%
20-Oct-2026 13:35:20	2026-10-20 13:35:20 INFO tclean::::casa ...done
20-Oct-2026 13:35:20	2026-10-20 13:35:20 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:35:20	2026-10-20 13:35:20 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00165737, over full image : 0.00416103
20-Oct-2026 13:35:20	2026-10-20 13:35:20 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 15372, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
20-Oct-2026 13:35:20	2026-10-20 13:35:20 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=15372, Gain=0.1
20-Oct-2026 13:35:20	2026-10-20 13:35:20 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:35:20	2026-10-20 13:35:20 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() Initial maximum residual is 0.00611654 within the mask
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() Diverging due to large scale?
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() 0 0
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() 1 0
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() 2 0.00119706
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO MatrixCleaner::clean() 3 0.00439182
20-Oct-2026 13:35:39	2026-10-20 13:35:20 WARN SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185) MSClean minor cycle stopped at large scale negative or diverging
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->3 [3], model=7.52261->7.52378, peakres=0.00165737->0.00164932, Exited multiscale minor cycle without reaching any stopping criterion.
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 4631 iterations.
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:35:39	2026-10-20 13:35:20 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:35:39
20-Oct-2026 13:35:39	0%....10....20....30...
20-Oct-2026 13:35:39	0%....10....
20-Oct-2026 13:35:39	0%....10....20
20-Oct-2026 13:35:39	0%....10....20
20-Oct-2026 13:35:39	0%....10....20....30...
20-Oct-2026 13:35:39	0%....10....20....30..
20-Oct-2026 13:35:39	0%....10....20....30...20......40.40..40..40............30.....30...50..50...50....5030..........40.40...........6060..60...40...60........5050..........70..70.....70..70.....50.60...60...........80.80.80.80..............70.60.70.........90.90.9090........100%
20-Oct-2026 13:35:39	...100%
20-Oct-2026 13:35:39	...80..70..100%
20-Oct-2026 13:35:39	...100%
20-Oct-2026 13:35:39	.....80.....90..80....100%
20-Oct-2026 13:35:39	..90.....100%
20-Oct-2026 13:35:39	..90....100%
20-Oct-2026 13:35:39	2026-10-20 13:35:39 INFO tclean::::casa ...done
20-Oct-2026 13:35:39	2026-10-20 13:35:39 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00164955, over full image : 0.00417018
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 15369, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=15369, Gain=0.1
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:35:39	2026-10-20 13:35:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() Initial maximum residual is 0.0439545 within the mask
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() 200 0.00421756 0.137474
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() 400 0.00376598 0.224474
20-Oct-2026 13:35:59	2026-10-20 13:35:40 INFO MatrixCleaner::clean() 600 0.00347909 0.292141
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO MatrixCleaner::clean() 800 0.00324404 0.347883
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO MatrixCleaner::clean() 1000 0.00103649 0.389349
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO MatrixCleaner::clean() Reached stopping threshold 0.001 at iteration 1138
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO MatrixCleaner::clean() Optimum flux is 0.000997873
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO MatrixCleaner::clean() 0 0.0427511
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO MatrixCleaner::clean() 1 0.0178992
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO MatrixCleaner::clean() 2 0.228908
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO MatrixCleaner::clean() 3 0.131411
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->1139 [1139], model=7.5238->7.94464, peakres=0.00164955->0.000997874, Reached cyclethreshold.
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 5770 iterations.
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:35:59	2026-10-20 13:35:41 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:35:59
20-Oct-2026 13:35:59	0%....10....20
20-Oct-2026 13:35:59	0%....10....20....30...
20-Oct-2026 13:35:59	0%....10....20
20-Oct-2026 13:35:59	0%....10....20
20-Oct-2026 13:35:59	0%....10....20....30..
20-Oct-2026 13:35:59	0%....10....20....30....
20-Oct-2026 13:35:59	0%....10....20....30.......40.40...40..40...........30......30....50....5050...3050...........40..40..........6060..60.40.....60........50...50.......70.70.......7070.50.........60.60........80...80...8080..........60...70.....70.....90...90.9090......100%
20-Oct-2026 13:35:59	.....100%
20-Oct-2026 13:35:59	.70.80....100%
20-Oct-2026 13:35:59	....100%
20-Oct-2026 13:35:59	.....80....9080......100%
20-Oct-2026 13:35:59	...90......100%
20-Oct-2026 13:35:59	90....100%
20-Oct-2026 13:35:59	2026-10-20 13:35:59 INFO tclean::::casa ...done
20-Oct-2026 13:35:59	2026-10-20 13:35:59 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:35:59	2026-10-20 13:35:59 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00115385, over full image : 0.00358587
20-Oct-2026 13:35:59	2026-10-20 13:35:59 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 14230, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=14230, Gain=0.1
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:35:59	2026-10-20 13:36:00 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO MatrixCleaner::clean() Initial maximum residual is 0.00390444 within the mask
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO MatrixCleaner::clean() Diverging due to large scale?
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO MatrixCleaner::clean() 0 0
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO MatrixCleaner::clean() 1 0
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO MatrixCleaner::clean() 2 0.00225037
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO MatrixCleaner::clean() 3 0.00278148
20-Oct-2026 13:36:19	2026-10-20 13:36:00 WARN SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185) MSClean minor cycle stopped at large scale negative or diverging
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->7 [7], model=7.94467->7.94688, peakres=0.00115385->0.00113489, Exited multiscale minor cycle without reaching any stopping criterion.
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 5777 iterations.
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:36:19	2026-10-20 13:36:00 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:36:19
20-Oct-2026 13:36:19	0%....10....20....30....
20-Oct-2026 13:36:19	0%....10....20
20-Oct-2026 13:36:19	0%....10....20.
20-Oct-2026 13:36:19	0%....10....20.
20-Oct-2026 13:36:19	0%....10....20....30....
20-Oct-2026 13:36:19	0%....10....20....30....40
20-Oct-2026 13:36:19	0%....10....20....30....40.40...40..........30.......30...50...50.50.....50..30.......40.40...........6060...60....40..60........50.50.........70..70.....7070.......50..60..60..........80.80..80..80.............7060...70........90.90.9090........100%
20-Oct-2026 13:36:19	...100%
20-Oct-2026 13:36:19	...8070....100%
20-Oct-2026 13:36:19	...100%
20-Oct-2026 13:36:19	......80....90..80....100%
20-Oct-2026 13:36:19	..90......100%
20-Oct-2026 13:36:19	.90....100%
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO tclean::::casa ...done
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00113663, over full image : 0.00359494
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 14223, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=14223, Gain=0.1
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::clean() Initial maximum residual is 0.0278256 within the mask
20-Oct-2026 13:36:19	2026-10-20 13:36:19 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:36:19	2026-10-20 13:36:20 INFO MatrixCleaner::clean() 200 0.00301924 0.0897329
20-Oct-2026 13:36:19	2026-10-20 13:36:20 INFO MatrixCleaner::clean() Reached stopping threshold 0.001 at iteration 273
20-Oct-2026 13:36:19	2026-10-20 13:36:20 INFO MatrixCleaner::clean() Optimum flux is 0.000998931
20-Oct-2026 13:36:19	2026-10-20 13:36:20 INFO MatrixCleaner::clean() 0 0.00602461
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO MatrixCleaner::clean() 1 0.00234237
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO MatrixCleaner::clean() 2 0.0573979
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO MatrixCleaner::clean() 3 0.0485432
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->274 [274], model=7.94692->8.06104, peakres=0.00113663->0.000998932, Reached cyclethreshold.
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 6051 iterations.
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:36:39	2026-10-20 13:36:20 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:36:39
20-Oct-2026 13:36:39	0%....10....20....30....40
20-Oct-2026 13:36:39	0%....10....20....30....
20-Oct-2026 13:36:39	0%....10....20.
20-Oct-2026 13:36:39	0%....10....20
20-Oct-2026 13:36:39	0%....10....20.
20-Oct-2026 13:36:39	0%....10....20....30....40
20-Oct-2026 13:36:39	0%....10....20....30.....40...40...........30......30.....50.50..50..30..50..........40.40...........6060..6040......60.......50.50...........70..70.....7070.50.......60...60.........80..80..80.80.............6070...70........90.90.90.90.......100%
20-Oct-2026 13:36:39	......100%
20-Oct-2026 13:36:39	8070....100%
20-Oct-2026 13:36:39	...100%
20-Oct-2026 13:36:39	.....80.....90..80....100%
20-Oct-2026 13:36:39	..90.....100%
20-Oct-2026 13:36:39	..90....100%
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO tclean::::casa ...done
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00102517, over full image : 0.00342835
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 13949, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=13949, Gain=0.1
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() Initial maximum residual is 0.00310325 within the mask
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() Diverging due to large scale?
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() 0 0
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() 1 0
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() 2 0.000926441
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO MatrixCleaner::clean() 3 0.00233484
20-Oct-2026 13:36:39	2026-10-20 13:36:39 WARN SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185) MSClean minor cycle stopped at large scale negative or diverging
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->4 [4], model=8.06113->8.06196, peakres=0.00102517->0.00103213, Exited multiscale minor cycle without reaching any stopping criterion.
20-Oct-2026 13:36:39	2026-10-20 13:36:39 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 6055 iterations.
20-Oct-2026 13:36:58	2026-10-20 13:36:39 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:36:58	2026-10-20 13:36:39 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:36:58	2026-10-20 13:36:39 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:36:58	2026-10-20 13:36:39 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:36:58	2026-10-20 13:36:39 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:36:58	2026-10-20 13:36:39 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:36:58	2026-10-20 13:36:39 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:36:58	2026-10-20 13:36:40 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:36:58	2026-10-20 13:36:40 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:36:58	2026-10-20 13:36:40 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:36:58
20-Oct-2026 13:36:58	0%....10....20....30...
20-Oct-2026 13:36:58	0%....10....
20-Oct-2026 13:36:58	0%....10....20
20-Oct-2026 13:36:58	0%....10....20....30..
20-Oct-2026 13:36:58	0%....10....20
20-Oct-2026 13:36:58	0%....10....20....30...
20-Oct-2026 13:36:58	0%....10....20....30...20......40.40.40...40............30......30...50.50...50....3050...........40.40..........6060..6040.......60.......5050...........70..70....70..7050.........60.60.........80....8080.80..........60...70....70......90...90.9090........100%
20-Oct-2026 13:36:58	...100%
20-Oct-2026 13:36:58	.70.80.....100%
20-Oct-2026 13:36:58	...100%
20-Oct-2026 13:36:58	.....80....9080......100%
20-Oct-2026 13:36:58	...90.....100%
20-Oct-2026 13:36:58	.90....100%
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO tclean::::casa ...done
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00103202, over full image : 0.00343162
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 13945, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=13945, Gain=0.1
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() Cleaning using given mask
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() Starting iteration
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() Initial maximum residual is 0.0233413 within the mask
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() Reached stopping threshold 0.001 at iteration 73
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() Optimum flux is 0.000999513
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() 0 0.0018235
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() 1 0.000401149
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() 2 0.0138873
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO MatrixCleaner::clean() 3 0.0159111
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->74 [74], model=8.06206->8.09389, peakres=0.00103202->0.000999513, Reached cyclethreshold.
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 6129 iterations.
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
20-Oct-2026 13:36:58	2026-10-20 13:36:58 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
20-Oct-2026 13:37:39	2026-10-20 13:36:58 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
20-Oct-2026 13:37:39	2026-10-20 13:36:58 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
20-Oct-2026 13:37:39	2026-10-20 13:36:58 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
20-Oct-2026 13:37:39	2026-10-20 13:36:58 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
20-Oct-2026 13:37:39	2026-10-20 13:36:58 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
20-Oct-2026 13:37:39	2026-10-20 13:36:58 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
20-Oct-2026 13:37:39	2026-10-20 13:36:58 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:37:39
20-Oct-2026 13:37:39	0%....10....20
20-Oct-2026 13:37:39	0%....10....20
20-Oct-2026 13:37:39	0%....10....20....30..
20-Oct-2026 13:37:39	0%....10....20....30....
20-Oct-2026 13:37:39	0%....10....20
20-Oct-2026 13:37:39	0%....10....20....30...
20-Oct-2026 13:37:39	0%....10....20....30........4040...40.40...........30.......30.....50.50.50..50......30......40.40...........60.60..60.....6040.......50...50.........70...70....7070.......50..60.60...........80.80..80..80.............7060...70.......90...909090........100%
20-Oct-2026 13:37:39	....100%
20-Oct-2026 13:37:39	..80....100%
20-Oct-2026 13:37:39	70...100%
20-Oct-2026 13:37:39	......80...90...80....100%
20-Oct-2026 13:37:39	..90.....100%
20-Oct-2026 13:37:39	..90....100%
20-Oct-2026 13:37:39	2026-10-20 13:37:17 INFO tclean::::casa ...done
20-Oct-2026 13:37:39	2026-10-20 13:37:17 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
20-Oct-2026 13:37:39	2026-10-20 13:37:17 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00100932, over full image : 0.00337241
20-Oct-2026 13:37:39	2026-10-20 13:37:17 INFO tclean::::casa Reached global stopping criterion : threshold
20-Oct-2026 13:37:39	2026-10-20 13:37:17 INFO tclean::::casa getSummary call: fullsummary=False
20-Oct-2026 13:37:39	2026-10-20 13:37:17 INFO SDAlgorithmBase::restore [3c391_ctm_spw0_multiscale] : Restoring model image.
20-Oct-2026 13:37:39	2026-10-20 13:37:18 INFO SIImageStore::restore Beam for chan : 0 : 17.0639 arcsec, 14.9556 arcsec, 21.6316 deg
20-Oct-2026 13:37:39	2026-10-20 13:37:38 INFO tclean::::casa checkJobs. Blocking for nodes to finish
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO tclean::::casa ...done
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO tclean::::casa Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO tclean::::casa Searching for images with prefix '3c391_ctm_spw0_multiscale'... Found these, writing history into them: ['3c391_ctm_spw0_multiscale.sumwt', '3c391_ctm_spw0_multiscale.pb', '3c391_ctm_spw0_multiscale.model', '3c391_ctm_spw0_multiscale.residual', '3c391_ctm_spw0_multiscale.psf', '3c391_ctm_spw0_multiscale.weight', '3c391_ctm_spw0_multiscale.mask', '3c391_ctm_spw0_multiscale.gridwt_moswt', '3c391_ctm_spw0_multiscale.image']
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO tclean::::casa Task tclean complete. Start time: 2026-10-20 13:32:34.824075 End time: 2026-10-20 13:37:39.169573
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO tclean::::casa ##### End Task: tclean #####
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO tclean::::casa ##########################################
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::::casa ##########################################
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::::casa ##### Begin Task: impbcor #####
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::::casa impbcor( imagename='3c391_ctm_spw0_multiscale.image', pbimage='3c391_ctm_spw0_multiscale.pb', outfile='3c391_ctm_spw0_multiscale.pbcorimage', overwrite=False, box='', region='', chans='', stokes='', mask='', mode='divide', cutoff=-1.0, stretch=False )
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::CasacRegionManager::fromBCS No directional region specified. Using full positional plane.
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::CasacRegionManager::fromBCS Using all spectral channels.
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::CasacRegionManager::fromBCS Using polarizations ALL
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::ImageRegion::fromRecord Selected bounding box :
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::ImageRegion::fromRecord+ [0, 0, 0, 0] to [479, 479, 0, 0] (18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz to 18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz)
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::ImageRegion::fromRecord Selected bounding box :
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::ImageRegion::fromRecord+ [0, 0, 0, 0] to [479, 479, 0, 0] (18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz to 18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz)
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::SubImageFactory::createImage Creating image '3c391_ctm_spw0_multiscale.pbcorimage' of shape [480, 480, 1, 1]
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::ImageMaskAttacher::makeMask Created mask `mask0'
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::::casa Task impbcor complete. Start time: 2026-10-20 13:37:39.175317 End time: 2026-10-20 13:37:39.294772
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::::casa ##### End Task: impbcor #####
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO impbcor::::casa ##########################################
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::::casa ##########################################
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::::casa ##### Begin Task: imstat #####
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::::casa imstat( imagename='3c391_ctm_spw0_multiscale.pbcorimage', axes=[], region='', box='', chans='', stokes='', listit=True, verbose=True, mask='', stretch=False, logfile='', append=True, algorithm='classic', fence=-1.0, center='mean', lside=True, zscore=-1.0, maxiter=-1, clmethod='auto', niter=3 )
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::CasacRegionManager::fromBCS No directional region specified. Using full positional plane.
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::CasacRegionManager::fromBCS Using all spectral channels.
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::CasacRegionManager::fromBCS Using polarizations ALL
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::image::statistics Determining stats for image 3c391_ctm_spw0_multiscale.pbcorimage
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageRegion::fromRecord Selected bounding box :
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageRegion::fromRecord+ [0, 0, 0, 0] to [479, 479, 0, 0] (18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz to 18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz)
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup Statistics calculated using Classic algorithm
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup Regions ---
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (pixel) [blc]: [0, 0, 0, 0]
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (pixel) [trc]: [479, 479, 0, 0]
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (world) [blcf]: 18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (world) [trcf]: 18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: Values ---
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- flux density [flux]: 9.38568 Jy
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- number of points [npts]: 115808
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- maximum value [max]: 0.155552 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- minimum value [min]: -0.0064779 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- position of max value (pixel) [maxpos]: [288, 256, 0, 0]
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- position of min value (pixel) [minpos]: [70, 141, 0, 0]
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- position of max value (world) [maxposf]: 18:49:16.243, -00.55.00.579, I, 4.59835e+09Hz
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- position of min value (world) [minposf]: 18:49:52.582, -00.59.48.073, I, 4.59835e+09Hz
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Sum of pixel values [sum]: 434.244 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Sum of squared pixel values [sumsq]: 17.8609 Jy/beam.Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: Statistics ---
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Mean of the pixel values [mean]: 0.00374969 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Variance of the pixel values : 0.00014017 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Standard deviation of the Mean [sigma]: 0.0118393 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Root mean square [rms]: 0.0124189 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Median of the pixel values [median]: 0.000433106 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Median of the deviations [medabsdevmed]: 0.00135104 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- IQR [quartile]: 0.00292432 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- First quartile [q1]: -0.000746207 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Third quartile [q3]: 0.00217812 Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics Sum column unit = Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics+ Mean column unit = Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics+ Std_dev column unit = Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics+ Minimum column unit = Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics+ Maximum column unit = Jy/beam
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics Npts Sum Mean Rms Std_dev Minimum Maximum
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics 1.158080e+05 4.342437e+02 3.749686e-03 1.241888e-02 1.183933e-02 -6.477905e-03 1.555524e-01
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::::casa Task imstat complete. Start time: 2026-10-20 13:37:39.300033 End time: 2026-10-20 13:37:39.316882
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::::casa ##### End Task: imstat #####
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::::casa ##########################################
20-Oct-2026 13:37:39	Expected image peak at pixels [288 256], got [288 256], tolerance exact
20-Oct-2026 13:37:39	Expected pbcor image peak of 0.15553903579711914, got 0.15555235743522644, tolerance 4.0%
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::::casa ##########################################
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::::casa ##### Begin Task: imstat #####
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::::casa imstat( imagename='3c391_ctm_spw0_multiscale.residual', axes=[], region='3c391_clean_mask.crtf', box='', chans='', stokes='', listit=True, verbose=True, mask='', stretch=False, logfile='', append=True, algorithm='classic', fence=-1.0, center='mean', lside=True, zscore=-1.0, maxiter=-1, clmethod='auto', niter=3 )
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::RegionTextParser::_determineVersion RegionTextParser::_determineVersion: Found spec version 0
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::AsciiRegionFileParser::_parse Combined 1 image regions (which excludes any annotation regions)
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::AsciiRegionFileParser::_parse The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::CasacRegionManager::fromBCS Region read from CRTF file 3c391_clean_mask.crtf
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::image::statistics Determining stats for image 3c391_ctm_spw0_multiscale.residual
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageRegion::fromRecord Selected bounding box :
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageRegion::fromRecord+ [140, 145, 0, 0] to [316, 315, 0, 0] (18:49:40.913, -00.59.38.078, I, 4.59835e+09Hz to 18:49:11.576, -00.52.33.079, I, 4.59835e+09Hz)
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup Statistics calculated using Classic algorithm
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup Regions ---
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (pixel) [blc]: [140, 145, 0, 0]
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (pixel) [trc]: [316, 315, 0, 0]
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (world) [blcf]: 18:49:40.913, -00.59.38.078, I, 4.59835e+09Hz
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (world) [trcf]: 18:49:11.576, -00.52.33.079, I, 4.59835e+09Hz
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: Values ---
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- number of points [npts]: 19718
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- maximum value [max]: 0.00100932
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- minimum value [min]: -0.000927068
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- position of max value (pixel) [maxpos]: [271, 262, 0, 0]
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- position of min value (pixel) [minpos]: [292, 235, 0, 0]
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- position of max value (world) [maxposf]: 18:49:19.077, -00.54.45.580, I, 4.59835e+09Hz
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- position of min value (world) [minposf]: 18:49:15.576, -00.55.53.079, I, 4.59835e+09Hz
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Sum of pixel values [sum]: 8.2566
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Sum of squared pixel values [sumsq]: 0.00574812
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: Statistics ---
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Mean of the pixel values [mean]: 0.000418734
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Variance of the pixel values : 1.16184e-07
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Standard deviation of the Mean [sigma]: 0.000340858
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Root mean square [rms]: 0.000539923
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Median of the pixel values [median]: 0.000457813
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Median of the deviations [medabsdevmed]: 0.000227509
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- IQR [quartile]: 0.000456822
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- First quartile [q1]: 0.000222317
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat:::: -- Third quartile [q3]: 0.000679139
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics Sum column unit =
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics+ Mean column unit =
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics+ Std_dev column unit =
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics+ Minimum column unit =
20-Oct-2026 13:37:39	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics+ Maximum column unit =
20-Oct-2026 13:37:56	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics Npts Sum Mean Rms Std_dev Minimum Maximum
20-Oct-2026 13:37:56	2026-10-20 13:37:39 INFO imstat::ImageStatsCalculator::statistics 1.971800e+04 8.256598e+00 4.187341e-04 5.399227e-04 3.408580e-04 -9.270681e-04 1.009318e-03
20-Oct-2026 13:37:56	2026-10-20 13:37:39 INFO imstat::::casa Task imstat complete. Start time: 2026-10-20 13:37:39.319314 End time: 2026-10-20 13:37:39.335360
20-Oct-2026 13:37:56	2026-10-20 13:37:39 INFO imstat::::casa ##### End Task: imstat #####
20-Oct-2026 13:37:56	2026-10-20 13:37:39 INFO imstat::::casa ##########################################
20-Oct-2026 13:37:56	Expected peak residual of 0.0010077510960400105, got 0.0010093175806105137, tolerance 10.0%
20-Oct-2026 13:37:56	Expected rms residual of 0.000542584067811474, got 0.0005399226868223044, tolerance 10.0%
20-Oct-2026 13:37:56	2026-10-20 13:37:39 INFO name:: No table opened.
20-Oct-2026 13:37:56	2026-10-20 13:37:39 INFO name:: No table opened.
20-Oct-2026 13:37:56	.
20-Oct-2026 13:37:56	----------------------------------------------------------------------
20-Oct-2026 13:37:56	Ran 1 test in 857.560s
20-Oct-2026 13:37:56
20-Oct-2026 13:37:56	OK
20-Oct-2026 13:37:56	Executor result: 0
20-Oct-2026 13:37:56	Failed tests:[]
20-Oct-2026 13:37:56
20-Oct-2026 13:37:56	Results: [{'testname': 'test_stk_vla_users_continuum_from_SDM', 'runtime': '0:14:35.659217', 'returncode': 0, 'testerr': []}]
20-Oct-2026 13:37:56	Stopping Xvfb.
20-Oct-2026 13:37:56	Stopping framebuffer <Popen: returncode: None args: ['Xvfb', ':106', '-screen', '0', '2048x2048x2...>
20-Oct-2026 13:37:56	Virtual frame buffer deployed at :106 with pid 119 successfully shutdown
20-Oct-2026 13:37:56	Xvfb stopped.
20-Oct-2026 13:37:57	cp: cannot stat '/home/casatest/work/test_stk_vla_users_continuum_from_SDM/*.png': No such file or directory
20-Oct-2026 13:37:57	total 774676
20-Oct-2026 13:37:57	-rw-r--r-- 1 casaci 15001 793262388 Oct 20 13:22 casa-6.7.7-16-py3.12.el8.tar.xz
20-Oct-2026 13:37:57	drwxr-xr-x 2 casaci root 24 Oct 20 13:37 html
20-Oct-2026 13:37:57	-rw-r--r-- 1 casaci casatest 388 Oct 20 13:37 xUnit-suite-mpi-8.xml
20-Oct-2026 13:37:57	drwxr-xr-x 2 casaci root 58 Oct 20 13:37 xml
20-Oct-2026 13:37:57	total 16
20-Oct-2026 13:37:57	-rw-r--r-- 1 casaci root 12471 Oct 20 13:37 index.html
20-Oct-2026 13:37:57	Finished task 'Script' with result: Success
20-Oct-2026 13:37:57	Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
20-Oct-2026 13:37:57	Parsing test results under /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-RELEASE677TESTSTKPY312-STCR85D...
20-Oct-2026 13:37:57	Finished task 'Parse test results' with result: Success
20-Oct-2026 13:37:57	Substituting variable: ${bamboo.capability.hostname} with cvpost134
20-Oct-2026 13:37:57	Substituting variable: ${bamboo.capability.hostname} with cvpost134
20-Oct-2026 13:37:57	Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
20-Oct-2026 13:37:57	Beginning to execute external process for build 'CASASPECS - Release 6.7.7 Test Stakeholder Python 3.12 - Test VLA Stakeholder Tests MPI Rocky 8.10 Docker #2 (CASASPECS-RELEASE677TESTSTKPY312-STCR85D-2)' ... running command line: /export/cvpost134-nvme/casa/bamboohome/temp/CASASPECS-RELEASE677TESTSTKPY312-STCR85D-2-ScriptBuildTask-239380737027568267.sh ... in: /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-RELEASE677TESTSTKPY312-STCR85D
20-Oct-2026 13:37:57	Starting cleanup
20-Oct-2026 13:38:07	test-container
20-Oct-2026 13:38:12	test-container
20-Oct-2026 13:38:12	Cleanup complete
20-Oct-2026 13:38:12	Finished task 'Cleanup' with result: Success
20-Oct-2026 13:38:12	Running post build plugin 'Docker Container Cleanup'
20-Oct-2026 13:38:12	Running post build plugin 'NCover Results Collector'
20-Oct-2026 13:38:12	Running post build plugin 'Build Results Label Collector'
20-Oct-2026 13:38:12	Running post build plugin 'Clover Results Collector'
20-Oct-2026 13:38:12	Running post build plugin 'npm Cache Cleanup'
20-Oct-2026 13:38:12	Running post build plugin 'Artifact Copier'
20-Oct-2026 13:38:12	Publishing an artifact: VLA Stakeholder MPI
20-Oct-2026 13:38:12	Finished publishing of artifact Non required job artifact Http Compression On : [VLA Stakeholder MPI], patterns: [html/**] anchored at: [dockershare] in 46.42 ms
20-Oct-2026 13:38:12	Finalising the build...
20-Oct-2026 13:38:12	Stopping timer.
20-Oct-2026 13:38:12	Build CASASPECS-RELEASE677TESTSTKPY312-STCR85D-2 completed.
20-Oct-2026 09:38:12	Running on server: post build plugin 'Build Hanging Detection Configuration'
20-Oct-2026 09:38:12	Running on server: post build plugin 'NCover Results Collector'
20-Oct-2026 09:38:12	Running on server: post build plugin 'Build Labeller'
20-Oct-2026 09:38:12	Running on server: post build plugin 'Clover Delta Calculator'
20-Oct-2026 09:38:12	Running on server: post build plugin 'Maven Dependencies Postprocessor'
20-Oct-2026 09:38:12	All post build plugins have finished
20-Oct-2026 09:38:12	Generating build results summary...
20-Oct-2026 09:38:12	Saving build results to disk...
20-Oct-2026 09:38:13	Store variable context...
20-Oct-2026 09:38:13	Finished building CASASPECS-RELEASE677TESTSTKPY312-STCR85D-2.

Build: #2 was successful

Job: Test VLA Stakeholder Tests MPI Rocky 8.10 Docker was successful

Stages & jobs

Default Stage

Build log

Build #2

Build: #2 was successful

Job: Test VLA Stakeholder Tests MPI Rocky 8.10 Docker was successful

Stages & jobs

Default Stage

Build log