Build log

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

16-Sep-2026 22:34:47	2026-09-16 22:34:16 INFO applycal::AgentFlagger::open Table type is Measurement Set
16-Sep-2026 22:34:47	2026-09-16 22:34:16 INFO FlagVersion::saveFlagVersion Creating new backup flag file called applycal_2
16-Sep-2026 22:34:47	2026-09-16 22:34:17 INFO applycal::::casa Task applycal complete. Start time: 2026-09-16 22:34:15.528629 End time: 2026-09-16 22:34:17.092015
16-Sep-2026 22:34:47	2026-09-16 22:34:17 INFO applycal::::casa ##### End Task: applycal #####
16-Sep-2026 22:34:47	2026-09-16 22:34:17 INFO applycal::::casa ##########################################
16-Sep-2026 22:34:47	2026-09-16 22:34:17 INFO applycal::::casa ##########################################
16-Sep-2026 22:34:47	2026-09-16 22:34:17 INFO applycal::::casa ##### Begin Task: applycal #####
16-Sep-2026 22:34:47	2026-09-16 22:34:17 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 )
16-Sep-2026 22:34:47	2026-09-16 22:34:17 INFO applycal::AgentFlagger::open Table type is Measurement Set
16-Sep-2026 22:34:47	2026-09-16 22:34:17 INFO FlagVersion::saveFlagVersion Creating new backup flag file called applycal_3
16-Sep-2026 22:34:47	2026-09-16 22:34:21 INFO applycal::::casa Task applycal complete. Start time: 2026-09-16 22:34:17.093249 End time: 2026-09-16 22:34:21.365901
16-Sep-2026 22:34:47	2026-09-16 22:34:21 INFO applycal::::casa ##### End Task: applycal #####
16-Sep-2026 22:34:47	2026-09-16 22:34:21 INFO applycal::::casa ##########################################
16-Sep-2026 22:34:47	2026-09-16 22:34:21 INFO split::::casa ##########################################
16-Sep-2026 22:34:47	2026-09-16 22:34:21 INFO split::::casa ##### Begin Task: split #####
16-Sep-2026 22:34:47	2026-09-16 22:34:21 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='' )
16-Sep-2026 22:34:47	2026-09-16 22:34:21 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
16-Sep-2026 22:34:47	2026-09-16 22:34:21 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
16-Sep-2026 22:34:47	2026-09-16 22:34:24 INFO ParallelDataHelper::::casa Finalizing MMS structure
16-Sep-2026 22:34:47	2026-09-16 22:34:24 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.
16-Sep-2026 22:34:47	2026-09-16 22:34:25 INFO split::::casa Task split complete. Start time: 2026-09-16 22:34:21.369007 End time: 2026-09-16 22:34:24.730616
16-Sep-2026 22:34:47	2026-09-16 22:34:25 INFO split::::casa ##### End Task: split #####
16-Sep-2026 22:34:47	2026-09-16 22:34:25 INFO split::::casa ##########################################
16-Sep-2026 22:34:47	2026-09-16 22:34:25 INFO statwt::::casa ##########################################
16-Sep-2026 22:34:47	2026-09-16 22:34:25 INFO statwt::::casa ##### Begin Task: statwt #####
16-Sep-2026 22:34:47	2026-09-16 22:34:25 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' )
16-Sep-2026 22:34:47	2026-09-16 22:34:25 INFO statwt::::casa Backup original flags before applying new flags
16-Sep-2026 22:34:47	2026-09-16 22:34:25 INFO AgentFlagger::open Table type is Measurement Set
16-Sep-2026 22:34:47	2026-09-16 22:34:25 INFO FlagVersion::saveFlagVersion Creating new backup flag file called statwt_1
16-Sep-2026 22:34:47	2026-09-16 22:34:25 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.
16-Sep-2026 22:34:47	2026-09-16 22:34:26 INFO StatWtTVI::StatWtTVI Using DATA to compute weights
16-Sep-2026 22:34:47	....10....20....30....40....50....60....70....80....90....100%
16-Sep-2026 22:34:47	2026-09-16 22:34:47 INFO StatWtTVI::summarizeFlagging Originally, 35.0215% of the data were flagged. StatWtTVI flagged an additional 0%.
16-Sep-2026 22:34:47	2026-09-16 22:34:47 INFO StatWtTVI::summarizeFlagging TOTAL FLAGGED DATA AFTER RUNNING STATWT: 35.0215%
16-Sep-2026 22:34:47	2026-09-16 22:34:47 INFO StatWtTVI::summarizeFlagging
16-Sep-2026 22:34:47	2026-09-16 22:34:47 INFO StatWtTVI::summarizeFlagging SPECTRAL_WINDOW SAMPLES_WITH_NON-ZERO_VARIANCE SAMPLES_WHERE_REAL_PART_VARIANCE_DIFFERS_BY_>50%_FROM_IMAGINARY_PART
16-Sep-2026 22:34:47	2026-09-16 22:34:47 INFO StatWtTVI::summarizeFlagging 0 997366 260704
16-Sep-2026 22:34:47	2026-09-16 22:34:47 INFO StatWtTVI::_logUsedChannels Weights are being computed using SPW 0, channels 0~63
16-Sep-2026 22:34:47	2026-09-16 22:34:47 INFO StatWtTVI::summarizeStats The mean of the computed weights is 321.368
16-Sep-2026 22:34:47	2026-09-16 22:34:47 INFO StatWtTVI::summarizeStats The variance of the computed weights is 348637
16-Sep-2026 22:34:47	2026-09-16 22:34:47 INFO StatWtTVI::summarizeStats Weights which had corresponding flags of True prior to running this application were not used to compute these stats.
16-Sep-2026 22:34:47	2026-09-16 22:34:48 INFO statwt::::casa Task statwt complete. Start time: 2026-09-16 22:34:24.771282 End time: 2026-09-16 22:34:47.577022
16-Sep-2026 22:34:47	2026-09-16 22:34:48 INFO statwt::::casa ##### End Task: statwt #####
16-Sep-2026 22:34:47	2026-09-16 22:34:48 INFO statwt::::casa ##########################################
16-Sep-2026 22:34:47	2026-09-16 22:34:48 INFO tclean::::casa ##########################################
16-Sep-2026 22:34:47	2026-09-16 22:34:48 INFO tclean::::casa ##### Begin Task: tclean #####
16-Sep-2026 22:34:47	2026-09-16 22:34:48 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 )
16-Sep-2026 22:35:40	2026-09-16 22:34:48 INFO tclean::::casa Verifying Input Parameters
16-Sep-2026 22:35:40	2026-09-16 22:34:51 INFO tclean::::casa setupCluster, Setting up 7 engines.
16-Sep-2026 22:35:40	2026-09-16 22:34:51 INFO SynthesisUtilMethods::continuumDataPartition
16-Sep-2026 22:35:40	2026-09-16 22:34:51 INFO SynthesisUtilMethods::continuumDataPartition + Rows = 657829 767459 [P][M]: 0:6 ROWNUMBER() >= 657829 && ROWNUMBER() <= 767459
16-Sep-2026 22:35:40	2026-09-16 22:34:51 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': ''}}}
16-Sep-2026 22:35:40	2026-09-16 22:34:51 INFO SynthesisImagerVi2::selectData MS : 3c391_ctm_mosaic_spw0.ms \| [Opened in readonly mode]
16-Sep-2026 22:35:40	2026-09-16 22:34:51 INFO SynthesisImagerVi2::selectData NRows selected : 767459
16-Sep-2026 22:35:40	2026-09-16 22:34:52 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
16-Sep-2026 22:35:40	2026-09-16 22:34:52 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
16-Sep-2026 22:35:40	2026-09-16 22:34:53 INFO SynthesisImagerVi2::defineImage Impars: start
16-Sep-2026 22:35:40	2026-09-16 22:34:53 INFO SynthesisImagerVi2::defineImage Shape: [480, 480, 1, 1] Spectral: [4.59835e+09] at [0] with increment [1.27992e+08]
16-Sep-2026 22:35:40	2026-09-16 22:34:53 INFO SynthesisImagerVi2::defineImage Set Gridding options for [3c391_ctm_spw0_multiscale] with ftmachine : mosaicft
16-Sep-2026 22:35:40	2026-09-16 22:34:53 INFO SynthesisImager::getVPRecord Using default Voltage Patterns from the VPManager
16-Sep-2026 22:35:40	2026-09-16 22:34:53 INFO VPSkyJones::VPSkyJones PB used EVLA
16-Sep-2026 22:35:40	2026-09-16 22:34:53 INFO SynthesisImagerVi2::nSubCubeFitInMemory Required memory: 0.2175 GB. Available mem.: 390.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 8.
16-Sep-2026 22:35:40	2026-09-16 22:34:53 INFO SynthesisImagerVi2::weight() Set imaging weights : Briggs weighting: sidelobes will be suppressed over full image
16-Sep-2026 22:35:40	2026-09-16 22:35:02 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:02 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:02 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:02 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:02 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:02 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:02 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:02 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:35:40	2026-09-16 22:35:03 INFO tclean::::casa ...done
16-Sep-2026 22:35:40	2026-09-16 22:35:03 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:35:40	2026-09-16 22:35:04 INFO tclean::::casa ...done
16-Sep-2026 22:35:40	2026-09-16 22:35:07 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:35:40	2026-09-16 22:35:08 INFO tclean::::casa ...done
16-Sep-2026 22:35:40	2026-09-16 22:35:13 INFO SynthesisImagerVi2::selectData MS : 3c391_ctm_mosaic_spw0.ms \| [Opened in readonly mode]
16-Sep-2026 22:35:40	2026-09-16 22:35:13 INFO SynthesisImagerVi2::selectData NRows selected : 767459
16-Sep-2026 22:35:40	2026-09-16 22:35:14 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
16-Sep-2026 22:35:40	2026-09-16 22:35:14 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
16-Sep-2026 22:35:40	2026-09-16 22:35:15 INFO SynthesisImagerVi2::defineImage Impars: start
16-Sep-2026 22:35:40	2026-09-16 22:35:15 INFO SynthesisImagerVi2::defineImage Shape: [480, 480, 1, 1] Spectral: [4.59835e+09] at [0] with increment [1.27992e+08]
16-Sep-2026 22:35:40	2026-09-16 22:35:15 INFO SynthesisImagerVi2::defineImage Set Gridding options for [3c391_ctm_spw0_multiscale] with ftmachine : mosaicft
16-Sep-2026 22:35:40	2026-09-16 22:35:15 INFO SynthesisImager::getVPRecord Using default Voltage Patterns from the VPManager
16-Sep-2026 22:35:40	2026-09-16 22:35:15 INFO VPSkyJones::VPSkyJones PB used EVLA
16-Sep-2026 22:35:40	2026-09-16 22:35:15 INFO SynthesisImagerVi2::nSubCubeFitInMemory Required memory: 0.2175 GB. Available mem.: 390.8 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 8.
16-Sep-2026 22:35:40	2026-09-16 22:35:15 INFO SynthesisImagerVi2::weight() Set imaging weights : Briggs weighting: sidelobes will be suppressed over full image
16-Sep-2026 22:35:40	2026-09-16 22:35:24 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:24 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:24 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:24 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:24 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:24 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:24 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
16-Sep-2026 22:35:40	2026-09-16 22:35:24 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:35:40	2026-09-16 22:35:25 INFO tclean::::casa ...done
16-Sep-2026 22:35:40	2026-09-16 22:35:25 INFO task_tclean::SynthesisDeconvolver::setupDeconvolution Set Deconvolution Options for [3c391_ctm_spw0_multiscale] : multiscale
16-Sep-2026 22:35:40	2026-09-16 22:35:25 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:35:40	2026-09-16 22:35:26 INFO tclean::::casa ...done
16-Sep-2026 22:35:40	2026-09-16 22:35:26 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:35:40
16-Sep-2026 22:35:40	0%....10....20....30....
16-Sep-2026 22:35:40	0%....10....20....30....40
16-Sep-2026 22:35:40	0%....10....20....30....40....50
16-Sep-2026 22:35:40	0%....10....20....30....40
16-Sep-2026 22:35:40	0%....10....20....30....40....50..40.................5060
16-Sep-2026 22:35:40	0%....10....20....30....40....50....
16-Sep-2026 22:35:40	0%....10....20....30....40....50....605060.60.......50................60...70.70.707060............60...........70.80..8080.80..70...........70.......80.......909090.90.........80...100%
16-Sep-2026 22:35:40	80...100%
16-Sep-2026 22:35:40	...100%
16-Sep-2026 22:35:40	...100%
16-Sep-2026 22:35:40	.90........100%
16-Sep-2026 22:35:40	...9090.....100%
16-Sep-2026 22:35:40	...100%
16-Sep-2026 22:35:40	2026-09-16 22:35:40 INFO tclean::::casa ...done
16-Sep-2026 22:35:52	2026-09-16 22:35:40 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
16-Sep-2026 22:35:52	2026-09-16 22:35:41 INFO SIImageStore::calcSensitivity [3c391_ctm_spw0_multiscale] Theoretical sensitivity (Jy/bm):1.06835e-05
16-Sep-2026 22:35:52	2026-09-16 22:35:41 INFO SIImageStore::getPSFGaussian Time to fit Gaussian to PSF 0.006147
16-Sep-2026 22:35:52	2026-09-16 22:35:41 INFO SIImageStore::printBeamSet Beam : 17.0639 arcsec, 14.9556 arcsec, 21.6316 deg
16-Sep-2026 22:35:52	2026-09-16 22:35:41 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:35:52	2026-09-16 22:35:42 INFO tclean::::casa ...done
16-Sep-2026 22:35:52	2026-09-16 22:35:42 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]
16-Sep-2026 22:35:52	2026-09-16 22:35:42 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
16-Sep-2026 22:35:52	2026-09-16 22:35:42 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
16-Sep-2026 22:35:52	2026-09-16 22:35:42 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
16-Sep-2026 22:35:52	2026-09-16 22:35:42 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
16-Sep-2026 22:35:52	2026-09-16 22:35:42 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
16-Sep-2026 22:35:52	2026-09-16 22:35:42 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
16-Sep-2026 22:35:52	2026-09-16 22:35:42 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
16-Sep-2026 22:35:52	2026-09-16 22:35:42 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:35:52	2026-09-16 22:35:42 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:35:52
16-Sep-2026 22:35:52	0%....10....20....30....40....50....60....70.
16-Sep-2026 22:35:52	0%....10....20....30....40....50....
16-Sep-2026 22:35:52	0%....10....20....30....40....50....60....70.
16-Sep-2026 22:35:52	0%....10....20....30....40....50....60....70.
16-Sep-2026 22:35:52	0%....10....20....30....40....50....60....70.
16-Sep-2026 22:35:52	0%....10....20....30....40....50.
16-Sep-2026 22:35:52	0%....10....20....30....40....50....6060....................80808080..60....70........70.............90.7090.909080........100%
16-Sep-2026 22:35:52	.....100%
16-Sep-2026 22:35:52	.......100%
16-Sep-2026 22:35:52	..100%
16-Sep-2026 22:35:52	..80...9080......100%
16-Sep-2026 22:35:52	...90......100%
16-Sep-2026 22:35:52	90....100%
16-Sep-2026 22:35:52	2026-09-16 22:35:52 INFO tclean::::casa ...done
16-Sep-2026 22:35:52	2026-09-16 22:35:52 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
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0, over full image : 0.123931
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO SynthesisDeconvolver::setupMask [3c391_ctm_spw0_multiscale] Setting up a mask from [3c391_clean_mask.crtf]
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO CasacRegionManager::fromBCS No directional region specified. Using full positional plane.
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO CasacRegionManager::fromBCS Using all spectral channels.
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO CasacRegionManager::fromBCS Using polarizations ALL
16-Sep-2026 22:35:52	2026-09-16 22:35:53 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.
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO ImageMaskAttacher::makeMask Created and initialized mask `mask0'
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO RegionTextParser::_determineVersion RegionTextParser::_determineVersion: Found spec version 0
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO AsciiRegionFileParser::_parse Combined 1 image regions (which excludes any annotation regions)
16-Sep-2026 22:35:52	2026-09-16 22:35:53 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).
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO SynthesisDeconvolver::setupMask ----------------------------------------------------------------------------------------------------------------------------------------
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO SynthesisDeconvolver::setupMask [3c391_ctm_spw0_multiscale] Number of pixels in the clean mask : 19718 out of a total of 230400 pixels. [ 8.55816 % ]
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.123931, over full image : 0.123931
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 20000, 'cyclethreshold': 0.011826693080365658, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.0118267, CycleNiter=20000, Gain=0.1
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:35:52	2026-09-16 22:35:53 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() Initial maximum residual is 0.469931 within the mask
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() 200 0.0711923 2.46328
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() 400 0.0143844 3.3664
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() Reached stopping threshold 0.0118267 at iteration 505
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() Optimum flux is 0.0117667
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() 0 0.0641231
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() 1 0.170624
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() 2 3.44997
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO MatrixCleaner::clean() 3 0.0269819
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->506 [506], model=0->3.71053, peakres=0.123931->0.0117667, Reached cyclethreshold.
16-Sep-2026 22:36:13	2026-09-16 22:35:53 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 506 iterations.
16-Sep-2026 22:36:13	2026-09-16 22:35:54 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]
16-Sep-2026 22:36:13	2026-09-16 22:35:54 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
16-Sep-2026 22:36:13	2026-09-16 22:35:54 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
16-Sep-2026 22:36:13	2026-09-16 22:35:54 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
16-Sep-2026 22:36:13	2026-09-16 22:35:54 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
16-Sep-2026 22:36:13	2026-09-16 22:35:54 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
16-Sep-2026 22:36:13	2026-09-16 22:35:54 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
16-Sep-2026 22:36:13	2026-09-16 22:35:54 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
16-Sep-2026 22:36:13	2026-09-16 22:35:54 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:36:13	2026-09-16 22:35:54 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:36:13
16-Sep-2026 22:36:13	0%....10....
16-Sep-2026 22:36:13	0%....10....
16-Sep-2026 22:36:13	0%....10....20....30..2020....40..
16-Sep-2026 22:36:13	0%....10....20....30....40
16-Sep-2026 22:36:13	0%....10....20.
16-Sep-2026 22:36:13	0%....10....20....30....
16-Sep-2026 22:36:13	0%....10....20....30....40.40...........30.....30......50..50.50....5030.........40..40...........60.60.60.40.......60....50..50...........70..70.....7070..50.....60.60............80....8080.80...........60..70...70.........90.90.9090........100%
16-Sep-2026 22:36:13	...100%
16-Sep-2026 22:36:13	..7080....100%
16-Sep-2026 22:36:13	....100%
16-Sep-2026 22:36:13	....80.....90..80....100%
16-Sep-2026 22:36:13	..90......100%
16-Sep-2026 22:36:13	.90....100%
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO tclean::::casa ...done
16-Sep-2026 22:36:13	2026-09-16 22:36:13 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
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.0139507, over full image : 0.0139507
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 19494, 'cyclethreshold': 0.0013313069939613342, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.00133131, CycleNiter=19494, Gain=0.1
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:36:13	2026-09-16 22:36:13 INFO MatrixCleaner::clean() Initial maximum residual is 0.0489674 within the mask
16-Sep-2026 22:36:33	2026-09-16 22:36:13 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:36:33	2026-09-16 22:36:13 INFO MatrixCleaner::clean() Diverging due to large scale?
16-Sep-2026 22:36:33	2026-09-16 22:36:13 INFO MatrixCleaner::clean() 0 0.00117514
16-Sep-2026 22:36:33	2026-09-16 22:36:13 INFO MatrixCleaner::clean() 1 0.0082739
16-Sep-2026 22:36:33	2026-09-16 22:36:13 INFO MatrixCleaner::clean() 2 0.257526
16-Sep-2026 22:36:33	2026-09-16 22:36:13 INFO MatrixCleaner::clean() 3 0.0260557
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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.
16-Sep-2026 22:36:33	2026-09-16 22:36:13 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 580 iterations.
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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]
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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
16-Sep-2026 22:36:33	2026-09-16 22:36:13 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
16-Sep-2026 22:36:33	2026-09-16 22:36:13 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:36:33	2026-09-16 22:36:13 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:36:33
16-Sep-2026 22:36:33	0%....10....20
16-Sep-2026 22:36:33	0%....10....20....30..
16-Sep-2026 22:36:33	0%....10....20....30...
16-Sep-2026 22:36:33	0%....10....20
16-Sep-2026 22:36:33	0%....10....20....30...
16-Sep-2026 22:36:33	0%....10....20
16-Sep-2026 22:36:33	0%....10....20....30.........4040....40.40...........30......30......50.50.50..50.30..........40..40...........60..6060...40...60.......50.50..........70...70.....7070...50...60..60...........80.....80.80.80..........60.70.....70.......90...909090........100%
16-Sep-2026 22:36:33	....100%
16-Sep-2026 22:36:33	80.70....100%
16-Sep-2026 22:36:33	.....100%
16-Sep-2026 22:36:33	....80....90..80...100%
16-Sep-2026 22:36:33	...90......100%
16-Sep-2026 22:36:33	.90....100%
16-Sep-2026 22:36:33	2026-09-16 22:36:31 INFO tclean::::casa ...done
16-Sep-2026 22:36:33	2026-09-16 22:36:31 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
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.0113207, over full image : 0.0113207
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 19420, 'cyclethreshold': 0.0010803299956023693, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.00108033, CycleNiter=19420, Gain=0.1
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() Initial maximum residual is 0.264782 within the mask
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() 200 0.0247378 0.650537
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() 400 0.0193002 1.07716
16-Sep-2026 22:36:33	2026-09-16 22:36:32 INFO MatrixCleaner::clean() 600 0.01604 1.39109
16-Sep-2026 22:36:33	2026-09-16 22:36:33 INFO MatrixCleaner::clean() 800 0.0136018 1.6325
16-Sep-2026 22:36:33	2026-09-16 22:36:33 INFO MatrixCleaner::clean() 1000 0.00400562 1.8299
16-Sep-2026 22:36:33	2026-09-16 22:36:33 INFO MatrixCleaner::clean() 1200 0.00352989 1.99719
16-Sep-2026 22:36:33	2026-09-16 22:36:33 INFO MatrixCleaner::clean() 1400 0.00313125 2.14576
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 1600 0.00822065 2.28115
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 1800 0.0073705 2.39586
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 2000 0.00665978 2.491
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 0 0.133816
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 1 0.164411
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 2 1.90829
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 3 0.284476
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() Failed to reach stopping threshold
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() Initial maximum residual is 0.00226852 within the mask
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() Diverging due to large scale?
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 0 0.000226852
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 1 0
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 2 0.000666034
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO MatrixCleaner::clean() 3 0
16-Sep-2026 22:36:52	2026-09-16 22:36:34 WARN SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185) MSClean minor cycle stopped at large scale negative or diverging
16-Sep-2026 22:36:52	2026-09-16 22:36:34 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.
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 2582 iterations.
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:36:52	2026-09-16 22:36:34 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:36:52
16-Sep-2026 22:36:52	0%....10....20
16-Sep-2026 22:36:52	0%....10....20....30...
16-Sep-2026 22:36:52	0%....10....20
16-Sep-2026 22:36:52	0%....10....20
16-Sep-2026 22:36:52	0%....10....20....30...
16-Sep-2026 22:36:52	0%....10....20....30...
16-Sep-2026 22:36:52	0%....10....20....30........40.40...40.40...........30......30.....50...5050.30..50...........40..40..........60..604060......60........5050..........70...70.....7070.50.......60..60.........80....80..8080........60...70.......70.....90....909090........100%
16-Sep-2026 22:36:52	70...100%
16-Sep-2026 22:36:52	..80.....100%
16-Sep-2026 22:36:52	....100%
16-Sep-2026 22:36:52	.....80...9080.....100%
16-Sep-2026 22:36:52	....90......100%
16-Sep-2026 22:36:52	90....100%
16-Sep-2026 22:36:52	2026-09-16 22:36:52 INFO tclean::::casa ...done
16-Sep-2026 22:36:52	2026-09-16 22:36:52 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
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00330019, over full image : 0.00573342
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 17418, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=17418, Gain=0.1
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:36:52	2026-09-16 22:36:53 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() Initial maximum residual is 0.0127214 within the mask
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() Diverging due to large scale?
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() 0 0
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() 1 0
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() 2 0.00739823
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO MatrixCleaner::clean() 3 0.00900338
16-Sep-2026 22:37:11	2026-09-16 22:36:53 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
16-Sep-2026 22:37:11	2026-09-16 22:36:53 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.
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 2589 iterations.
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:37:11	2026-09-16 22:36:53 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:37:11
16-Sep-2026 22:37:11	0%....10....20....30....
16-Sep-2026 22:37:11	0%....10....20.
16-Sep-2026 22:37:11	0%....10....20
16-Sep-2026 22:37:11	0%....10....20.
16-Sep-2026 22:37:11	0%....10....20....30....
16-Sep-2026 22:37:11	0%....10....20....30....40
16-Sep-2026 22:37:11	0%....10....20....30....40...4040............30......30......50..5050..50.30..........40..40...........60.60.60.40....60.........50.50..........70...70....7070.50........60.60..........80.80...80.80.........60...70.......70......90..909090........100%
16-Sep-2026 22:37:11	....100%
16-Sep-2026 22:37:11	70.80....100%
16-Sep-2026 22:37:11	.....100%
16-Sep-2026 22:37:11	.....80...9080.....100%
16-Sep-2026 22:37:11	...90......100%
16-Sep-2026 22:37:11	.90....100%
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO tclean::::casa ...done
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO SynthesisNormalizer::gatherImages Gather residual images : [/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7] onto :3c391_ctm_spw0_multiscale
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00319188, over full image : 0.00573304
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 17411, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=17411, Gain=0.1
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::clean() Initial maximum residual is 0.0902081 within the mask
16-Sep-2026 22:37:11	2026-09-16 22:37:11 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:37:33	2026-09-16 22:37:12 INFO MatrixCleaner::clean() 200 0.00807465 0.25898
16-Sep-2026 22:37:33	2026-09-16 22:37:12 INFO MatrixCleaner::clean() 400 0.00683434 0.425356
16-Sep-2026 22:37:33	2026-09-16 22:37:12 INFO MatrixCleaner::clean() 600 0.00611194 0.55079
16-Sep-2026 22:37:33	2026-09-16 22:37:12 INFO MatrixCleaner::clean() 800 0.00190047 0.649999
16-Sep-2026 22:37:33	2026-09-16 22:37:12 INFO MatrixCleaner::clean() 1000 0.00514406 0.736802
16-Sep-2026 22:37:33	2026-09-16 22:37:13 INFO MatrixCleaner::clean() 1200 0.0021316 0.812211
16-Sep-2026 22:37:33	2026-09-16 22:37:13 INFO MatrixCleaner::clean() 1400 0.00442275 0.875158
16-Sep-2026 22:37:33	2026-09-16 22:37:13 INFO MatrixCleaner::clean() 1600 0.00141233 0.933863
16-Sep-2026 22:37:33	2026-09-16 22:37:13 INFO MatrixCleaner::clean() 1800 0.00132057 0.989256
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() 2000 0.00164392 1.03837
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() 0 0.0904452
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() 1 0.0555198
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() 2 0.67114
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() 3 0.221262
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() Failed to reach stopping threshold
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() Initial maximum residual is 0.00364809 within the mask
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() Diverging due to large scale?
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() 0 0.00160586
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() 1 0.000327359
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() 2 0.00617268
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO MatrixCleaner::clean() 3 0.00273267
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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.
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 4628 iterations.
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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]
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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
16-Sep-2026 22:37:33	2026-09-16 22:37:14 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
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:37:33	2026-09-16 22:37:14 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:37:33
16-Sep-2026 22:37:33	0%....10....20....30....40
16-Sep-2026 22:37:33	0%....10....20.
16-Sep-2026 22:37:33	0%....10....20
16-Sep-2026 22:37:33	0%....10....20.
16-Sep-2026 22:37:33	0%....10....20....30....
16-Sep-2026 22:37:33	0%....10....20....30....
16-Sep-2026 22:37:33	0%....10....20....30....40..40..40...........30.....30.......50.50.50..50.30..........40..40...........60.60.60..40...60.......50..50...........70..70.....7070..50....60....60..........80...80.80.80...........60.70...70..........90..909090........100%
16-Sep-2026 22:37:33	....100%
16-Sep-2026 22:37:33	.7080....100%
16-Sep-2026 22:37:33	.....100%
16-Sep-2026 22:37:33	...80.....90..80....100%
16-Sep-2026 22:37:33	..90......100%
16-Sep-2026 22:37:33	.90....100%
16-Sep-2026 22:37:33	2026-09-16 22:37:33 INFO tclean::::casa ...done
16-Sep-2026 22:37:33	2026-09-16 22:37:33 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
16-Sep-2026 22:37:33	2026-09-16 22:37:33 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00165737, over full image : 0.00416103
16-Sep-2026 22:37:33	2026-09-16 22:37:33 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 15372, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
16-Sep-2026 22:37:33	2026-09-16 22:37:33 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=15372, Gain=0.1
16-Sep-2026 22:37:33	2026-09-16 22:37:33 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:37:33	2026-09-16 22:37:33 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() Initial maximum residual is 0.00611654 within the mask
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() Diverging due to large scale?
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() 0 0
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() 1 0
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() 2 0.00119706
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO MatrixCleaner::clean() 3 0.00439182
16-Sep-2026 22:37:51	2026-09-16 22:37:33 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
16-Sep-2026 22:37:51	2026-09-16 22:37:33 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.
16-Sep-2026 22:37:51	2026-09-16 22:37:33 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 4631 iterations.
16-Sep-2026 22:37:51	2026-09-16 22:37:33 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]
16-Sep-2026 22:37:51	2026-09-16 22:37:33 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
16-Sep-2026 22:37:51	2026-09-16 22:37:33 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
16-Sep-2026 22:37:51	2026-09-16 22:37:33 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
16-Sep-2026 22:37:51	2026-09-16 22:37:33 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
16-Sep-2026 22:37:51	2026-09-16 22:37:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
16-Sep-2026 22:37:51	2026-09-16 22:37:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
16-Sep-2026 22:37:51	2026-09-16 22:37:34 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
16-Sep-2026 22:37:51	2026-09-16 22:37:34 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:37:51	2026-09-16 22:37:34 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:37:51
16-Sep-2026 22:37:51	0%....10....
16-Sep-2026 22:37:51	0%....10....20....30.
16-Sep-2026 22:37:51	0%....10....20....30...
16-Sep-2026 22:37:51	0%....10....20
16-Sep-2026 22:37:51	0%....10....20....30..
16-Sep-2026 22:37:51	0%....10....20
16-Sep-2026 22:37:51	0%....10....20....30...20........40.40..40..40...........30......30.....50..50.50.30...50..........40.40...........6060..4060.......60.......50.50..........70..70.....7070.50.......60...60..........80...8080.80........60.....70.....70......90...9090.90.......100%
16-Sep-2026 22:37:51	70...100%
16-Sep-2026 22:37:51	..80.....100%
16-Sep-2026 22:37:51	...100%
16-Sep-2026 22:37:51	......80...9080.....100%
16-Sep-2026 22:37:51	....90......100%
16-Sep-2026 22:37:51	90....100%
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO tclean::::casa ...done
16-Sep-2026 22:37:51	2026-09-16 22:37:52 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
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00164955, over full image : 0.00417018
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 15369, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=15369, Gain=0.1
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:37:51	2026-09-16 22:37:52 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() Initial maximum residual is 0.0439545 within the mask
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() 200 0.00421756 0.137474
16-Sep-2026 22:38:11	2026-09-16 22:37:52 INFO MatrixCleaner::clean() 400 0.00376598 0.224474
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO MatrixCleaner::clean() 600 0.00347909 0.292141
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO MatrixCleaner::clean() 800 0.00324404 0.347883
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO MatrixCleaner::clean() 1000 0.00103649 0.389349
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO MatrixCleaner::clean() Reached stopping threshold 0.001 at iteration 1138
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO MatrixCleaner::clean() Optimum flux is 0.000997873
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO MatrixCleaner::clean() 0 0.0427511
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO MatrixCleaner::clean() 1 0.0178992
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO MatrixCleaner::clean() 2 0.228908
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO MatrixCleaner::clean() 3 0.131411
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->1139 [1139], model=7.5238->7.94464, peakres=0.00164955->0.000997874, Reached cyclethreshold.
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 5770 iterations.
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6, /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7]
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n4.model
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n5.model
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n6.model
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /home/casatest/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n7.model
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:38:11	2026-09-16 22:37:53 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:38:11
16-Sep-2026 22:38:11	0%....10....20
16-Sep-2026 22:38:11	0%....10....20
16-Sep-2026 22:38:11	0%....10....20....30...
16-Sep-2026 22:38:11	0%....10....20
16-Sep-2026 22:38:11	0%....10....20....30...
16-Sep-2026 22:38:11	0%....10....20....30..
16-Sep-2026 22:38:11	0%....10....20....30.........4040.....4040...........30......30.....50...5050..5030...........40...40.........60...60.6040.....60........5050.........70....70.....7070..50.......60.60.........80....80..80.80.........60..70......70.....90....909090......100%
16-Sep-2026 22:38:11	......100%
16-Sep-2026 22:38:11	70.80....100%
16-Sep-2026 22:38:11	.....100%
16-Sep-2026 22:38:11	.....80...90.80....100%
16-Sep-2026 22:38:11	...90......100%
16-Sep-2026 22:38:11	.90....100%
16-Sep-2026 22:38:11	2026-09-16 22:38:11 INFO tclean::::casa ...done
16-Sep-2026 22:38:11	2026-09-16 22:38:12 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
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00115385, over full image : 0.00358587
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 14230, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=14230, Gain=0.1
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:38:11	2026-09-16 22:38:12 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO MatrixCleaner::clean() Initial maximum residual is 0.00390444 within the mask
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO MatrixCleaner::clean() Diverging due to large scale?
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO MatrixCleaner::clean() 0 0
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO MatrixCleaner::clean() 1 0
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO MatrixCleaner::clean() 2 0.00225037
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO MatrixCleaner::clean() 3 0.00278148
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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.
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 5777 iterations.
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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]
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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
16-Sep-2026 22:38:30	2026-09-16 22:38:12 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
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:38:30	2026-09-16 22:38:12 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:38:30
16-Sep-2026 22:38:30	0%....10....20....30....
16-Sep-2026 22:38:30	0%....10....20.
16-Sep-2026 22:38:30	0%....10....20....30...
16-Sep-2026 22:38:30	0%....10....20.
16-Sep-2026 22:38:30	0%....10....20
16-Sep-2026 22:38:30	0%....10....20....30....
16-Sep-2026 22:38:30	0%....10....20....30....4040....40.40...........30......30.......50.5050..30.50...........40..40........60...60.40.60.....60.......50..50.......70.....70.....707050.........60.60.........80.....80.80.80........60...70.....70......90....9090.90......100%
16-Sep-2026 22:38:30	.70.....100%
16-Sep-2026 22:38:30	80.....100%
16-Sep-2026 22:38:30	....100%
16-Sep-2026 22:38:30	.....80...9080.....100%
16-Sep-2026 22:38:30	....90.....100%
16-Sep-2026 22:38:30	.90....100%
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO tclean::::casa ...done
16-Sep-2026 22:38:30	2026-09-16 22:38:30 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
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00113663, over full image : 0.00359494
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 14223, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=14223, Gain=0.1
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::clean() Initial maximum residual is 0.0278256 within the mask
16-Sep-2026 22:38:30	2026-09-16 22:38:30 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:38:30	2026-09-16 22:38:31 INFO MatrixCleaner::clean() 200 0.00301924 0.0897329
16-Sep-2026 22:38:30	2026-09-16 22:38:31 INFO MatrixCleaner::clean() Reached stopping threshold 0.001 at iteration 273
16-Sep-2026 22:38:30	2026-09-16 22:38:31 INFO MatrixCleaner::clean() Optimum flux is 0.000998931
16-Sep-2026 22:38:30	2026-09-16 22:38:31 INFO MatrixCleaner::clean() 0 0.00602461
16-Sep-2026 22:38:49	2026-09-16 22:38:31 INFO MatrixCleaner::clean() 1 0.00234237
16-Sep-2026 22:38:49	2026-09-16 22:38:31 INFO MatrixCleaner::clean() 2 0.0573979
16-Sep-2026 22:38:49	2026-09-16 22:38:31 INFO MatrixCleaner::clean() 3 0.0485432
16-Sep-2026 22:38:49	2026-09-16 22:38:31 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->274 [274], model=7.94692->8.06104, peakres=0.00113663->0.000998932, Reached cyclethreshold.
16-Sep-2026 22:38:49	2026-09-16 22:38:31 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 6051 iterations.
16-Sep-2026 22:38:49	2026-09-16 22:38:31 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]
16-Sep-2026 22:38:49	2026-09-16 22:38:31 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
16-Sep-2026 22:38:49	2026-09-16 22:38:31 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
16-Sep-2026 22:38:49	2026-09-16 22:38:31 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
16-Sep-2026 22:38:49	2026-09-16 22:38:31 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
16-Sep-2026 22:38:49	2026-09-16 22:38:31 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
16-Sep-2026 22:38:49	2026-09-16 22:38:31 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
16-Sep-2026 22:38:49	2026-09-16 22:38:31 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
16-Sep-2026 22:38:49	2026-09-16 22:38:31 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:38:49	2026-09-16 22:38:31 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:38:49
16-Sep-2026 22:38:49	0%....10....20.
16-Sep-2026 22:38:49	0%....10....20....30....
16-Sep-2026 22:38:49	0%....10....20
16-Sep-2026 22:38:49	0%....10....20.
16-Sep-2026 22:38:49	0%....10....20....30....
16-Sep-2026 22:38:49	0%....10....20....30....
16-Sep-2026 22:38:49	0%....10....20....30....4040.40..40............30.....30.........505050..5030............40.40............6060.40..60...60.......50...50..........70....70...707050.......60..60...........80.80...80..80........60...70...70..........90..909090.........100%
16-Sep-2026 22:38:49	70....100%
16-Sep-2026 22:38:49	80....100%
16-Sep-2026 22:38:49	.....100%
16-Sep-2026 22:38:49	....80....9080......100%
16-Sep-2026 22:38:49	..90......100%
16-Sep-2026 22:38:49	.90....100%
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO tclean::::casa ...done
16-Sep-2026 22:38:49	2026-09-16 22:38:49 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
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00102517, over full image : 0.00342835
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 13949, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=13949, Gain=0.1
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() Initial maximum residual is 0.00310325 within the mask
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() Diverging due to large scale?
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() 0 0
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() 1 0
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() 2 0.000926441
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO MatrixCleaner::clean() 3 0.00233484
16-Sep-2026 22:38:49	2026-09-16 22:38:49 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
16-Sep-2026 22:38:49	2026-09-16 22:38:49 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.
16-Sep-2026 22:38:49	2026-09-16 22:38:49 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 6055 iterations.
16-Sep-2026 22:39:08	2026-09-16 22:38:49 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]
16-Sep-2026 22:39:08	2026-09-16 22:38:49 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
16-Sep-2026 22:39:08	2026-09-16 22:38:49 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
16-Sep-2026 22:39:08	2026-09-16 22:38:49 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
16-Sep-2026 22:39:08	2026-09-16 22:38:50 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
16-Sep-2026 22:39:08	2026-09-16 22:38:50 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
16-Sep-2026 22:39:08	2026-09-16 22:38:50 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
16-Sep-2026 22:39:08	2026-09-16 22:38:50 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
16-Sep-2026 22:39:08	2026-09-16 22:38:50 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:39:08	2026-09-16 22:38:50 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:39:08
16-Sep-2026 22:39:08	0%....10....20....30..
16-Sep-2026 22:39:08	0%....10....20
16-Sep-2026 22:39:08	0%....10....20
16-Sep-2026 22:39:08	0%....10....
16-Sep-2026 22:39:08	0%....10....20....30...
16-Sep-2026 22:39:08	0%....10....20....30..
16-Sep-2026 22:39:08	0%....10....20....30....20......40.40.40...40............30...30.........5050.50...5030..........40..40.........60...60...60.40....60.....50..50.........70......70...7070...50...60.....60........80...80...8080...........60.70...70........90....909090.......100%
16-Sep-2026 22:39:08	...70...100%
16-Sep-2026 22:39:08	80....100%
16-Sep-2026 22:39:08	...100%
16-Sep-2026 22:39:08	.....80.....90.80.....100%
16-Sep-2026 22:39:08	..90......100%
16-Sep-2026 22:39:08	.90....100%
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO tclean::::casa ...done
16-Sep-2026 22:39:08	2026-09-16 22:39:08 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
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00103202, over full image : 0.00343162
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 13945, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=13945, Gain=0.1
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() Cleaning using given mask
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() Starting iteration
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() Initial maximum residual is 0.0233413 within the mask
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() Reached stopping threshold 0.001 at iteration 73
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() Optimum flux is 0.000999513
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() 0 0.0018235
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() 1 0.000401149
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() 2 0.0138873
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO MatrixCleaner::clean() 3 0.0159111
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->74 [74], model=8.06206->8.09389, peakres=0.00103202->0.000999513, Reached cyclethreshold.
16-Sep-2026 22:39:08	2026-09-16 22:39:08 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 6129 iterations.
16-Sep-2026 22:39:08	2026-09-16 22:39:08 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]
16-Sep-2026 22:39:08	2026-09-16 22:39:08 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
16-Sep-2026 22:39:08	2026-09-16 22:39:08 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
16-Sep-2026 22:39:48	2026-09-16 22:39:08 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
16-Sep-2026 22:39:48	2026-09-16 22:39:08 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
16-Sep-2026 22:39:48	2026-09-16 22:39:08 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
16-Sep-2026 22:39:48	2026-09-16 22:39:08 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
16-Sep-2026 22:39:48	2026-09-16 22:39:08 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
16-Sep-2026 22:39:48	2026-09-16 22:39:08 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
16-Sep-2026 22:39:48	2026-09-16 22:39:08 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:39:48
16-Sep-2026 22:39:48	0%....10....20
16-Sep-2026 22:39:48	0%....10....20
16-Sep-2026 22:39:48	0%....10....20
16-Sep-2026 22:39:48	0%....10....20....30...
16-Sep-2026 22:39:48	0%....10....20....30....
16-Sep-2026 22:39:48	0%....10....20....30..
16-Sep-2026 22:39:48	0%....10....20....30........4040....40.40...........30......30.....50...5050..5030...........40...40..........60..606040......60........5050..........70...70.....7070.50.........6060.........80....80..8080..........60..70......70......90...909090........100%
16-Sep-2026 22:39:48	....100%
16-Sep-2026 22:39:48	70.80....100%
16-Sep-2026 22:39:48	.....100%
16-Sep-2026 22:39:48	.....80...9080.....100%
16-Sep-2026 22:39:48	....90.....100%
16-Sep-2026 22:39:48	.90....100%
16-Sep-2026 22:39:48	2026-09-16 22:39:26 INFO tclean::::casa ...done
16-Sep-2026 22:39:48	2026-09-16 22:39:26 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
16-Sep-2026 22:39:48	2026-09-16 22:39:26 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00100932, over full image : 0.00337241
16-Sep-2026 22:39:48	2026-09-16 22:39:26 INFO tclean::::casa Reached global stopping criterion : threshold
16-Sep-2026 22:39:48	2026-09-16 22:39:26 INFO tclean::::casa getSummary call: fullsummary=False
16-Sep-2026 22:39:48	2026-09-16 22:39:26 INFO SDAlgorithmBase::restore [3c391_ctm_spw0_multiscale] : Restoring model image.
16-Sep-2026 22:39:48	2026-09-16 22:39:27 INFO SIImageStore::restore Beam for chan : 0 : 17.0639 arcsec, 14.9556 arcsec, 21.6316 deg
16-Sep-2026 22:39:48	2026-09-16 22:39:47 INFO tclean::::casa checkJobs. Blocking for nodes to finish
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO tclean::::casa ...done
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO tclean::::casa Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO tclean::::casa Searching for images with prefix '3c391_ctm_spw0_multiscale'... Found these, writing history into them: ['3c391_ctm_spw0_multiscale.model', '3c391_ctm_spw0_multiscale.residual', '3c391_ctm_spw0_multiscale.mask', '3c391_ctm_spw0_multiscale.sumwt', '3c391_ctm_spw0_multiscale.psf', '3c391_ctm_spw0_multiscale.pb', '3c391_ctm_spw0_multiscale.gridwt_moswt', '3c391_ctm_spw0_multiscale.weight', '3c391_ctm_spw0_multiscale.image']
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO tclean::::casa Task tclean complete. Start time: 2026-09-16 22:34:47.610488 End time: 2026-09-16 22:39:48.373990
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO tclean::::casa ##### End Task: tclean #####
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO tclean::::casa ##########################################
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::::casa ##########################################
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::::casa ##### Begin Task: impbcor #####
16-Sep-2026 22:39:48	2026-09-16 22:39:48 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 )
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::CasacRegionManager::fromBCS No directional region specified. Using full positional plane.
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::CasacRegionManager::fromBCS Using all spectral channels.
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::CasacRegionManager::fromBCS Using polarizations ALL
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::ImageRegion::fromRecord Selected bounding box :
16-Sep-2026 22:39:48	2026-09-16 22:39:48 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)
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::ImageRegion::fromRecord Selected bounding box :
16-Sep-2026 22:39:48	2026-09-16 22:39:48 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)
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::SubImageFactory::createImage Creating image '3c391_ctm_spw0_multiscale.pbcorimage' of shape [480, 480, 1, 1]
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::ImageMaskAttacher::makeMask Created mask `mask0'
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::::casa Task impbcor complete. Start time: 2026-09-16 22:39:48.380016 End time: 2026-09-16 22:39:48.499552
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::::casa ##### End Task: impbcor #####
16-Sep-2026 22:39:48	2026-09-16 22:39:48 INFO impbcor::::casa ##########################################
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::::casa ##########################################
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::::casa ##### Begin Task: imstat #####
16-Sep-2026 22:39:48	2026-09-16 22:39:49 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 )
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::CasacRegionManager::fromBCS No directional region specified. Using full positional plane.
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::CasacRegionManager::fromBCS Using all spectral channels.
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::CasacRegionManager::fromBCS Using polarizations ALL
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::image::statistics Determining stats for image 3c391_ctm_spw0_multiscale.pbcorimage
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageRegion::fromRecord Selected bounding box :
16-Sep-2026 22:39:48	2026-09-16 22:39:49 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)
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup Statistics calculated using Classic algorithm
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup Regions ---
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (pixel) [blc]: [0, 0, 0, 0]
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (pixel) [trc]: [479, 479, 0, 0]
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (world) [blcf]: 18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (world) [trcf]: 18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: Values ---
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- flux density [flux]: 9.38568 Jy
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- number of points [npts]: 115808
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- maximum value [max]: 0.155552 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- minimum value [min]: -0.0064779 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- position of max value (pixel) [maxpos]: [288, 256, 0, 0]
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- position of min value (pixel) [minpos]: [70, 141, 0, 0]
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- position of max value (world) [maxposf]: 18:49:16.243, -00.55.00.579, I, 4.59835e+09Hz
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- position of min value (world) [minposf]: 18:49:52.582, -00.59.48.073, I, 4.59835e+09Hz
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Sum of pixel values [sum]: 434.244 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Sum of squared pixel values [sumsq]: 17.8609 Jy/beam.Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: Statistics ---
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Mean of the pixel values [mean]: 0.00374969 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Variance of the pixel values : 0.00014017 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Standard deviation of the Mean [sigma]: 0.0118393 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Root mean square [rms]: 0.0124189 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Median of the pixel values [median]: 0.000433106 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Median of the deviations [medabsdevmed]: 0.00135104 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- IQR [quartile]: 0.00292432 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- First quartile [q1]: -0.000746207 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Third quartile [q3]: 0.00217812 Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics Sum column unit = Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics+ Mean column unit = Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics+ Std_dev column unit = Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics+ Minimum column unit = Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics+ Maximum column unit = Jy/beam
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics Npts Sum Mean Rms Std_dev Minimum Maximum
16-Sep-2026 22:39:48	2026-09-16 22:39:49 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
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::::casa Task imstat complete. Start time: 2026-09-16 22:39:48.504737 End time: 2026-09-16 22:39:48.521895
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::::casa ##### End Task: imstat #####
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::::casa ##########################################
16-Sep-2026 22:39:48	Expected image peak at pixels [288 256], got [288 256], tolerance exact
16-Sep-2026 22:39:48	Expected pbcor image peak of 0.15553903579711914, got 0.15555235743522644, tolerance 4.0%
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::::casa ##########################################
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::::casa ##### Begin Task: imstat #####
16-Sep-2026 22:39:48	2026-09-16 22:39:49 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 )
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::RegionTextParser::_determineVersion RegionTextParser::_determineVersion: Found spec version 0
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::AsciiRegionFileParser::_parse Combined 1 image regions (which excludes any annotation regions)
16-Sep-2026 22:39:48	2026-09-16 22:39:49 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).
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::CasacRegionManager::fromBCS Region read from CRTF file 3c391_clean_mask.crtf
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::image::statistics Determining stats for image 3c391_ctm_spw0_multiscale.residual
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageRegion::fromRecord Selected bounding box :
16-Sep-2026 22:39:48	2026-09-16 22:39:49 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)
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup Statistics calculated using Classic algorithm
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup Regions ---
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (pixel) [blc]: [140, 145, 0, 0]
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (pixel) [trc]: [316, 315, 0, 0]
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (world) [blcf]: 18:49:40.913, -00.59.38.078, I, 4.59835e+09Hz
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (world) [trcf]: 18:49:11.576, -00.52.33.079, I, 4.59835e+09Hz
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: Values ---
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- number of points [npts]: 19718
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- maximum value [max]: 0.00100932
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- minimum value [min]: -0.000927068
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- position of max value (pixel) [maxpos]: [271, 262, 0, 0]
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- position of min value (pixel) [minpos]: [292, 235, 0, 0]
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- position of max value (world) [maxposf]: 18:49:19.077, -00.54.45.580, I, 4.59835e+09Hz
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- position of min value (world) [minposf]: 18:49:15.576, -00.55.53.079, I, 4.59835e+09Hz
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Sum of pixel values [sum]: 8.2566
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Sum of squared pixel values [sumsq]: 0.00574812
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: Statistics ---
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Mean of the pixel values [mean]: 0.000418734
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Variance of the pixel values : 1.16184e-07
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Standard deviation of the Mean [sigma]: 0.000340858
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Root mean square [rms]: 0.000539923
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Median of the pixel values [median]: 0.000457813
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Median of the deviations [medabsdevmed]: 0.000227509
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- IQR [quartile]: 0.000456822
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- First quartile [q1]: 0.000222317
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat:::: -- Third quartile [q3]: 0.000679139
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics Sum column unit =
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics+ Mean column unit =
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics+ Std_dev column unit =
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics+ Minimum column unit =
16-Sep-2026 22:39:48	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics+ Maximum column unit =
16-Sep-2026 22:40:10	2026-09-16 22:39:49 INFO imstat::ImageStatsCalculator::statistics Npts Sum Mean Rms Std_dev Minimum Maximum
16-Sep-2026 22:40:10	2026-09-16 22:39:49 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
16-Sep-2026 22:40:10	2026-09-16 22:39:49 INFO imstat::::casa Task imstat complete. Start time: 2026-09-16 22:39:48.524677 End time: 2026-09-16 22:39:48.541314
16-Sep-2026 22:40:10	2026-09-16 22:39:49 INFO imstat::::casa ##### End Task: imstat #####
16-Sep-2026 22:40:10	2026-09-16 22:39:49 INFO imstat::::casa ##########################################
16-Sep-2026 22:40:10	Expected peak residual of 0.0010077510960400105, got 0.0010093175806105137, tolerance 10.0%
16-Sep-2026 22:40:10	Expected rms residual of 0.000542584067811474, got 0.0005399226868223044, tolerance 10.0%
16-Sep-2026 22:40:10	2026-09-16 22:39:49 INFO name:: No table opened.
16-Sep-2026 22:40:10	2026-09-16 22:39:49 INFO name:: No table opened.
16-Sep-2026 22:40:10	.
16-Sep-2026 22:40:10	----------------------------------------------------------------------
16-Sep-2026 22:40:10	Ran 1 test in 848.950s
16-Sep-2026 22:40:10
16-Sep-2026 22:40:10	OK
16-Sep-2026 22:40:10	Executor result: 0
16-Sep-2026 22:40:10	Failed tests:[]
16-Sep-2026 22:40:10
16-Sep-2026 22:40:10	Results: [{'testname': 'test_stk_vla_users_continuum_from_SDM', 'runtime': '0:14:31.973678', 'returncode': 0, 'testerr': []}]
16-Sep-2026 22:40:10	Stopping Xvfb.
16-Sep-2026 22:40:10	Stopping framebuffer <Popen: returncode: None args: ['Xvfb', ':105', '-screen', '0', '2048x2048x2...>
16-Sep-2026 22:40:10	Virtual frame buffer deployed at :105 with pid 118 successfully shutdown
16-Sep-2026 22:40:10	Xvfb stopped.
16-Sep-2026 22:40:11	cp: cannot stat '/home/casatest/work/test_stk_vla_users_continuum_from_SDM/*.png': No such file or directory
16-Sep-2026 22:40:11	total 776564
16-Sep-2026 22:40:11	-rw-r--r-- 1 casaci 15001 795196040 Sep 16 22:24 casa-6.7.7-8-py3.12.el8.tar.xz
16-Sep-2026 22:40:11	drwxr-xr-x 2 casaci root 24 Sep 16 22:40 html
16-Sep-2026 22:40:11	-rw-r--r-- 1 casaci casatest 388 Sep 16 22:40 xUnit-suite-mpi-8.xml
16-Sep-2026 22:40:11	drwxr-xr-x 2 casaci root 58 Sep 16 22:40 xml
16-Sep-2026 22:40:11	total 16
16-Sep-2026 22:40:11	-rw-r--r-- 1 casaci root 12472 Sep 16 22:40 index.html
16-Sep-2026 22:40:11	Finished task 'Script' with result: Success
16-Sep-2026 22:40:11	Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
16-Sep-2026 22:40:11	Parsing test results under /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-STCR85D...
16-Sep-2026 22:40:11	Finished task 'Parse test results' with result: Success
16-Sep-2026 22:40:11	Substituting variable: ${bamboo.capability.hostname} with cvpost135
16-Sep-2026 22:40:11	Substituting variable: ${bamboo.capability.hostname} with cvpost135
16-Sep-2026 22:40:11	Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
16-Sep-2026 22:40:11	Beginning to execute external process for build 'CASASPECS - Master Test Stakeholder Python 3.12 - Test VLA Stakeholder Tests MPI Rocky 8.10 Docker #231 (CASASPECS-MASTERTESTSTKPY312-STCR85D-231)' ... running command line: /export/cvpost135-nvme/casa/bamboohome/temp/CASASPECS-MASTERTESTSTKPY312-STCR85D-231-ScriptBuildTask-11653237266915209604.sh ... in: /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-STCR85D
16-Sep-2026 22:40:11	Starting cleanup
16-Sep-2026 22:40:22	test-container
16-Sep-2026 22:40:26	test-container
16-Sep-2026 22:40:26	Cleanup complete
16-Sep-2026 22:40:26	Finished task 'Cleanup' with result: Success
16-Sep-2026 22:40:26	Running post build plugin 'Docker Container Cleanup'
16-Sep-2026 22:40:26	Running post build plugin 'NCover Results Collector'
16-Sep-2026 22:40:26	Running post build plugin 'Build Results Label Collector'
16-Sep-2026 22:40:26	Running post build plugin 'Clover Results Collector'
16-Sep-2026 22:40:26	Running post build plugin 'npm Cache Cleanup'
16-Sep-2026 22:40:26	Running post build plugin 'Artifact Copier'
16-Sep-2026 22:40:26	Publishing an artifact: VLA Stakeholder MPI
16-Sep-2026 22:40:26	Finished publishing of artifact Non required job artifact Http Compression On : [VLA Stakeholder MPI], patterns: [html/**] anchored at: [dockershare] in 109.3 ms
16-Sep-2026 22:40:26	Finalising the build...
16-Sep-2026 22:40:26	Stopping timer.
16-Sep-2026 22:40:26	Build CASASPECS-MASTERTESTSTKPY312-STCR85D-231 completed.
16-Sep-2026 18:40:26	Running on server: post build plugin 'Build Hanging Detection Configuration'
16-Sep-2026 18:40:26	Running on server: post build plugin 'NCover Results Collector'
16-Sep-2026 18:40:26	Running on server: post build plugin 'Build Labeller'
16-Sep-2026 18:40:26	Running on server: post build plugin 'Clover Delta Calculator'
16-Sep-2026 18:40:26	Running on server: post build plugin 'Maven Dependencies Postprocessor'
16-Sep-2026 18:40:26	All post build plugins have finished
16-Sep-2026 18:40:26	Generating build results summary...
16-Sep-2026 18:40:27	Saving build results to disk...
16-Sep-2026 18:40:27	Store variable context...
16-Sep-2026 18:40:27	Finished building CASASPECS-MASTERTESTSTKPY312-STCR85D-231.

Build: #231 was successful

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

Stages & jobs

Default Stage

Build log

Build #231

Build: #231 was successful

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

Stages & jobs

Default Stage

Build log