Build log

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

24-Feb-2026 02:23:49	2026-02-24 02:23:47 INFO Calibrater::solve For solint = inf, found 1 solution intervals.
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve Found good B Jones solutions in 1 solution intervals.
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Applying refant: ea21 refantmode = flex (hold alternate refants' phase constant) when refant flagged
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Writing solutions to table: 3c391_ctm_mosaic_10s_spw0.B0
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve ----- PER ANTENNA INFO -----
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve ANT: 0 ANT: 1 ANT: 2 ANT: 3 ANT: 4 ANT: 5 ANT: 6 ANT: 7 ANT: 8 ANT: 9 ANT: 10 ANT: 11 ANT: 12 ANT: 13 ANT: 14 ANT: 15 ANT: 16 ANT: 17 ANT: 18 ANT: 19 ANT: 20 ANT: 21 ANT: 22 ANT: 23 ANT: 24 ANT: 25
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve SPW: 0 [1, 1] [1, 1] [1, 1] [1, 1] [0, 0] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1] [0, 0] [1, 1] [0, 0] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1] [1, 1]
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve ----- PER SPW INFO -----
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve expected data_unflagged above_minblperant above_minsnr
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve SPW: 0 [1, 1] [1, 1] [1, 1] [1, 1]
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve ----- GLOBAL INFO -----
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve expected data_unflagged above_minblperant above_minsnr
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::solve [1, 1] [1, 1] [1, 1] [1, 1]
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO calibrater::solve Finished solving.
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO bandpass::::casa Task bandpass complete. Start time: 2026-02-23 21:23:46.464784 End time: 2026-02-23 21:23:49.193184
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO bandpass::::casa ##### End Task: bandpass #####
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO bandpass::::casa ##########################################
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO gaincal::::casa ##########################################
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO gaincal::::casa ##### Begin Task: gaincal #####
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO gaincal::::casa gaincal( vis='3c391_ctm_mosaic_10s_spw0.ms', caltable='3c391_ctm_mosaic_10s_spw0.G1', field='J1331+3030', spw='0:5~58', intent='', selectdata=True, timerange='', uvrange='', antenna='', scan='', observation='', msselect='', solint='inf', combine='', preavg=-1.0, refant='ea21', refantmode='flex', minblperant=4, minsnr=3.0, solnorm=False, normtype='mean', gaintype='G', smodel=[], calmode='ap', solmode='', rmsthresh=[], corrdepflags=False, append=False, splinetime=3600.0, npointaver=3, phasewrap=180.0, docallib=False, callib='', gaintable=['3c391_ctm_mosaic_10s_spw0.antpos', '3c391_ctm_mosaic_10s_spw0.K0', '3c391_ctm_mosaic_10s_spw0.B0'], gainfield=[], interp=['', '', 'nearest'], spwmap=[], parang=False )
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO gaincal::calibrater::open **Using NEW VI2-driven calibrater tool**
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO gaincal::calibrater::open Opening MS: 3c391_ctm_mosaic_10s_spw0.ms for calibration.
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO gaincal::Calibrater:: Initializing nominal selection to the whole MS.
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO gaincal::::casa NB: gaincal automatically excludes auto-correlations.
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO calibrater::setdata Beginning selectvis--(MSSelection version)-------
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO calibrater::reset Reseting solve/apply state
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::selectvis Performing selection on MeasurementSet
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::selectvis+ Selecting on spw: '0:5~58'
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::selectvis+ Selecting on field: 'J1331+3030'
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::selectvis+ Selecting with TaQL: 'ANTENNA1!=ANTENNA2'
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::selectvis By selection 850358 rows are reduced to 31922
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::selectvis Frequency selection:
24-Feb-2026 02:23:49	2026-02-24 02:23:49 INFO Calibrater::selectvis . Spw 0:5~58 (54 channels, step by 1)
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO calibrater::setdata chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1!=ANTENNA2'
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO (KAntPos Jones: Overriding with spwmap=[0] since KAntPos Jones is not spw-dependent)
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO (KAntPos Jones: Enforcing calWt()=false for phase/delay-like terms)
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO No VLATrDelCorr keyword in the antpos caltable; turning trop delay correction OFF.
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::setapply(type, applypar) . KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO (K Jones: Enforcing calWt()=false for phase/delay-like terms)
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::setapply(type, applypar) . K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::setapply(type, applypar) . B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=nearest,linear spwmap=[-1] calWt=true
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO calibrater::setsolve Beginning setsolve--(MSSelection version)-------
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::setsolve Arranging to SOLVE:
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::setsolve . G Jones: table=3c391_ctm_mosaic_10s_spw0.G1 append=false solint=inf refantmode='flex' refant='ea21' minsnr=3 apmode=AP solnorm=false
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO calibrater::solve Beginning solve-----------------------------
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::solve The following calibration terms are arranged for apply:
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::solve . KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::solve . B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=nearest,linear spwmap=[-1] calWt=true
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::solve . K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::solve The following calibration term is arranged for solve:
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::solve . G Jones: table=3c391_ctm_mosaic_10s_spw0.G1 append=false solint=inf refantmode='flex' refant='ea21' minsnr=3 apmode=AP solnorm=false
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO ChannelAverageTVI::parseConfiguration Channel bin is [-1]
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO FreqMetaData::calcFreqMeta Derived frequency meta-info for solutions:
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO FreqMetaData::calcFreqMeta Selected spw=0 (nchan=1) has centroid freq = 4599000000
24-Feb-2026 02:23:51	2026-02-24 02:23:49 INFO Calibrater::solve For solint = inf, found 4 solution intervals.
24-Feb-2026 02:23:51	Found no unflagged data at: (time=2010/04/24/08:02:21.4 field=0 spw=0 chan=0)
24-Feb-2026 02:23:51	2026-02-24 02:23:51 INFO Calibrater::solve Found good G Jones solutions in 3 solution intervals.
24-Feb-2026 02:23:51	2026-02-24 02:23:51 INFO Applying refant: ea21 refantmode = flex (hold alternate refants' phase constant) when refant flagged
24-Feb-2026 02:23:51	2026-02-24 02:23:51 INFO Writing solutions to table: 3c391_ctm_mosaic_10s_spw0.G1
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::solve ----- PER ANTENNA INFO -----
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::solve ANT: 0 ANT: 1 ANT: 2 ANT: 3 ANT: 4 ANT: 5 ANT: 6 ANT: 7 ANT: 8 ANT: 9 ANT: 10 ANT: 11 ANT: 12 ANT: 13 ANT: 14 ANT: 15 ANT: 16 ANT: 17 ANT: 18 ANT: 19 ANT: 20 ANT: 21 ANT: 22 ANT: 23 ANT: 24 ANT: 25
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::solve SPW: 0 [3, 3] [3, 3] [3, 3] [3, 3] [0, 0] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3] [0, 0] [3, 3] [0, 0] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3] [3, 3]
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::solve ----- PER SPW INFO -----
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::solve expected data_unflagged above_minblperant above_minsnr
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::solve SPW: 0 [4, 4] [3, 3] [3, 3] [3, 3]
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::solve ----- GLOBAL INFO -----
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::solve expected data_unflagged above_minblperant above_minsnr
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::solve [4, 4] [3, 3] [3, 3] [3, 3]
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO calibrater::solve Finished solving.
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::::casa Calibration solve statistics per spw: (expected/attempted/succeeded):
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::::casa Spw 0: 4/3/3
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::::casa Task gaincal complete. Start time: 2026-02-23 21:23:49.198422 End time: 2026-02-23 21:23:51.675531
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::::casa ##### End Task: gaincal #####
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::::casa ##########################################
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::::casa ##########################################
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::::casa ##### Begin Task: gaincal #####
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::::casa gaincal( vis='3c391_ctm_mosaic_10s_spw0.ms', caltable='3c391_ctm_mosaic_10s_spw0.G1', field='J1822-0938', spw='0:5~58', intent='', selectdata=True, timerange='', uvrange='', antenna='', scan='', observation='', msselect='', solint='inf', combine='', preavg=-1.0, refant='ea21', refantmode='flex', minblperant=4, minsnr=3.0, solnorm=False, normtype='mean', gaintype='G', smodel=[], calmode='ap', solmode='', rmsthresh=[], corrdepflags=False, append=True, splinetime=3600.0, npointaver=3, phasewrap=180.0, docallib=False, callib='', gaintable=['3c391_ctm_mosaic_10s_spw0.antpos', '3c391_ctm_mosaic_10s_spw0.K0', '3c391_ctm_mosaic_10s_spw0.B0'], gainfield=[], interp=[], spwmap=[], parang=False )
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::calibrater::open **Using NEW VI2-driven calibrater tool**
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::calibrater::open Opening MS: 3c391_ctm_mosaic_10s_spw0.ms for calibration.
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::Calibrater:: Initializing nominal selection to the whole MS.
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO gaincal::::casa NB: gaincal automatically excludes auto-correlations.
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO calibrater::setdata Beginning selectvis--(MSSelection version)-------
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO calibrater::reset Reseting solve/apply state
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::selectvis Performing selection on MeasurementSet
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::selectvis+ Selecting on spw: '0:5~58'
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::selectvis+ Selecting on field: 'J1822-0938'
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::selectvis+ Selecting with TaQL: 'ANTENNA1!=ANTENNA2'
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::selectvis By selection 850358 rows are reduced to 41256
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::selectvis Frequency selection:
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::selectvis . Spw 0:5~58 (54 channels, step by 1)
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO calibrater::setdata chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1!=ANTENNA2'
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO (KAntPos Jones: Overriding with spwmap=[0] since KAntPos Jones is not spw-dependent)
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO (KAntPos Jones: Enforcing calWt()=false for phase/delay-like terms)
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO No VLATrDelCorr keyword in the antpos caltable; turning trop delay correction OFF.
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::setapply(type, applypar) . KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
24-Feb-2026 02:23:51	2026-02-24 02:23:52 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO (K Jones: Enforcing calWt()=false for phase/delay-like terms)
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::setapply(type, applypar) . K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::setapply(type, applypar) . B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=true
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO calibrater::setsolve Beginning setsolve--(MSSelection version)-------
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::setsolve Arranging to SOLVE:
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::setsolve . G Jones: table=3c391_ctm_mosaic_10s_spw0.G1 append=true solint=inf refantmode='flex' refant='ea21' minsnr=3 apmode=AP solnorm=false
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO calibrater::solve Beginning solve-----------------------------
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::solve The following calibration terms are arranged for apply:
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::solve . KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::solve . B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=true
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::solve . K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::solve The following calibration term is arranged for solve:
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::solve . G Jones: table=3c391_ctm_mosaic_10s_spw0.G1 append=true solint=inf refantmode='flex' refant='ea21' minsnr=3 apmode=AP solnorm=false
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO ChannelAverageTVI::parseConfiguration Channel bin is [-1]
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO FreqMetaData::calcFreqMeta Derived frequency meta-info for solutions:
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO FreqMetaData::calcFreqMeta Selected spw=0 (nchan=1) has centroid freq = 4599000000
24-Feb-2026 02:23:58	2026-02-24 02:23:52 INFO Calibrater::solve For solint = inf, found 14 solution intervals.
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve Found good G Jones solutions in 14 solution intervals.
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Applying refant: ea21 refantmode = flex (hold alternate refants' phase constant) when refant flagged
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Appending solutions to table: 3c391_ctm_mosaic_10s_spw0.G1
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve ----- PER ANTENNA INFO -----
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve ANT: 0 ANT: 1 ANT: 2 ANT: 3 ANT: 4 ANT: 5 ANT: 6 ANT: 7 ANT: 8 ANT: 9 ANT: 10 ANT: 11 ANT: 12 ANT: 13 ANT: 14 ANT: 15 ANT: 16 ANT: 17 ANT: 18 ANT: 19 ANT: 20 ANT: 21 ANT: 22 ANT: 23 ANT: 24 ANT: 25
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve SPW: 0 [14, 14] [9, 9] [13, 13] [14, 14] [0, 0] [14, 14] [9, 9] [14, 14] [14, 14] [14, 14] [0, 0] [14, 14] [0, 0] [9, 9] [14, 14] [14, 14] [12, 12] [14, 14] [14, 14] [14, 14] [14, 14] [13, 13] [14, 14] [12, 12] [13, 13] [14, 14]
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve ----- PER SPW INFO -----
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve expected data_unflagged above_minblperant above_minsnr
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve SPW: 0 [14, 14] [14, 14] [14, 14] [14, 14]
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve ----- GLOBAL INFO -----
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve expected data_unflagged above_minblperant above_minsnr
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO Calibrater::solve [14, 14] [14, 14] [14, 14] [14, 14]
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO calibrater::solve Finished solving.
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO gaincal::::casa Calibration solve statistics per spw: (expected/attempted/succeeded):
24-Feb-2026 02:23:58	2026-02-24 02:23:54 INFO gaincal::::casa Spw 0: 14/14/14
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO gaincal::::casa Task gaincal complete. Start time: 2026-02-23 21:23:51.680772 End time: 2026-02-23 21:23:54.560426
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO gaincal::::casa ##### End Task: gaincal #####
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO gaincal::::casa ##########################################
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::::casa ##########################################
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::::casa ##### Begin Task: fluxscale #####
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::::casa fluxscale( vis='3c391_ctm_mosaic_10s_spw0.ms', caltable='3c391_ctm_mosaic_10s_spw0.G1', fluxtable='3c391_ctm_mosaic_10s_spw0.fluxscale1', reference=['J1331+3030'], transfer=['J1822-0938'], listfile='', append=False, refspwmap=[-1], gainthreshold=-1.0, antenna='', timerange='', scan='', incremental=False, fitorder=1, display=False )
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::calibrater::open **Using NEW VI2-driven calibrater tool**
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::calibrater::open Opening MS: 3c391_ctm_mosaic_10s_spw0.ms for calibration.
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::Calibrater:: Initializing nominal selection to the whole MS.
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::calibrater::fluxscale Beginning fluxscale--(MSSelection version)-------
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale:::: Found reference field(s): J1331+3030
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale:::: Found transfer field(s): J1822-0938
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale:::: Flux density for J1822-0938 in SpW=0 (freq=4.599e+09 Hz) is: 2.29736 +/- 0.00631833 (SNR = 363.603, N = 46)
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::Calibrater::fluxscale Storing result in 3c391_ctm_mosaic_10s_spw0.fluxscale1
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale:::: Writing solutions to table: 3c391_ctm_mosaic_10s_spw0.fluxscale1
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::::casa Task fluxscale complete. Start time: 2026-02-23 21:23:54.569816 End time: 2026-02-23 21:23:55.243760
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::::casa ##### End Task: fluxscale #####
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO fluxscale::::casa ##########################################
24-Feb-2026 02:23:58	Expected 46 input fluxboot solutions, got 46.0, tolerance exact
24-Feb-2026 02:23:58	Expected J1822-0938 bootstrapped flux of 2.2973563472684653, got 2.2973646087335093, tolerance 4.0%
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO applycal::::casa ##########################################
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO applycal::::casa ##### Begin Task: applycal #####
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO applycal::::casa applycal( vis='3c391_ctm_mosaic_10s_spw0.ms', field='J1331+3030', 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=['', 'J1331+3030', '', ''], interp=['', 'nearest', '', ''], spwmap=[], calwt=[False], parang=False, applymode='', flagbackup=True )
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO applycal::AgentFlagger::open Table type is Measurement Set
24-Feb-2026 02:23:58	2026-02-24 02:23:55 INFO FlagVersion::saveFlagVersion Creating new backup flag file called applycal_1
24-Feb-2026 02:23:58	2026-02-24 02:23:58 INFO applycal::::casa Task applycal complete. Start time: 2026-02-23 21:23:55.250862 End time: 2026-02-23 21:23:58.211223
24-Feb-2026 02:23:58	2026-02-24 02:23:58 INFO applycal::::casa ##### End Task: applycal #####
24-Feb-2026 02:23:58	2026-02-24 02:23:58 INFO applycal::::casa ##########################################
24-Feb-2026 02:23:58	2026-02-24 02:23:58 INFO applycal::::casa ##########################################
24-Feb-2026 02:23:58	2026-02-24 02:23:58 INFO applycal::::casa ##### Begin Task: applycal #####
24-Feb-2026 02:23:58	2026-02-24 02:23:58 INFO applycal::::casa applycal( vis='3c391_ctm_mosaic_10s_spw0.ms', field='J1822-0938', 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=['', 'nearest', '', ''], spwmap=[], calwt=[False], parang=False, applymode='', flagbackup=True )
24-Feb-2026 02:23:58	2026-02-24 02:23:58 INFO applycal::AgentFlagger::open Table type is Measurement Set
24-Feb-2026 02:24:35	2026-02-24 02:23:58 INFO FlagVersion::saveFlagVersion Creating new backup flag file called applycal_2
24-Feb-2026 02:24:35	2026-02-24 02:24:00 INFO applycal::::casa Task applycal complete. Start time: 2026-02-23 21:23:58.212412 End time: 2026-02-23 21:24:00.060594
24-Feb-2026 02:24:35	2026-02-24 02:24:00 INFO applycal::::casa ##### End Task: applycal #####
24-Feb-2026 02:24:35	2026-02-24 02:24:00 INFO applycal::::casa ##########################################
24-Feb-2026 02:24:35	2026-02-24 02:24:00 INFO applycal::::casa ##########################################
24-Feb-2026 02:24:35	2026-02-24 02:24:00 INFO applycal::::casa ##### Begin Task: applycal #####
24-Feb-2026 02:24:35	2026-02-24 02:24:00 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 )
24-Feb-2026 02:24:35	2026-02-24 02:24:00 INFO applycal::AgentFlagger::open Table type is Measurement Set
24-Feb-2026 02:24:35	2026-02-24 02:24:00 INFO FlagVersion::saveFlagVersion Creating new backup flag file called applycal_3
24-Feb-2026 02:24:35	2026-02-24 02:24:08 INFO applycal::::casa Task applycal complete. Start time: 2026-02-23 21:24:00.061738 End time: 2026-02-23 21:24:07.718416
24-Feb-2026 02:24:35	2026-02-24 02:24:08 INFO applycal::::casa ##### End Task: applycal #####
24-Feb-2026 02:24:35	2026-02-24 02:24:08 INFO applycal::::casa ##########################################
24-Feb-2026 02:24:35	2026-02-24 02:24:08 INFO split::::casa ##########################################
24-Feb-2026 02:24:35	2026-02-24 02:24:08 INFO split::::casa ##### Begin Task: split #####
24-Feb-2026 02:24:35	2026-02-24 02:24:08 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='' )
24-Feb-2026 02:24:35	2026-02-24 02:24:08 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
24-Feb-2026 02:24:35	2026-02-24 02:24:08 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
24-Feb-2026 02:24:35	2026-02-24 02:24:11 INFO ParallelDataHelper::::casa Finalizing MMS structure
24-Feb-2026 02:24:35	2026-02-24 02:24:11 INFO ms::createmultims Copying subtables from /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/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.
24-Feb-2026 02:24:35	2026-02-24 02:24:12 INFO split::::casa Task split complete. Start time: 2026-02-23 21:24:07.722219 End time: 2026-02-23 21:24:11.764179
24-Feb-2026 02:24:35	2026-02-24 02:24:12 INFO split::::casa ##### End Task: split #####
24-Feb-2026 02:24:35	2026-02-24 02:24:12 INFO split::::casa ##########################################
24-Feb-2026 02:24:35	2026-02-24 02:24:12 INFO statwt::::casa ##########################################
24-Feb-2026 02:24:35	2026-02-24 02:24:12 INFO statwt::::casa ##### Begin Task: statwt #####
24-Feb-2026 02:24:35	2026-02-24 02:24:12 INFO statwt::::casa statwt( vis='3c391_ctm_mosaic_spw0.ms', selectdata=True, field='', spw='', intent='', array='', observation='', scan='', combine='', timebin=1, slidetimebin=False, chanbin='spw', minsamp=2, statalg='classic', fence=-1.0, center='mean', lside=True, zscore=-1.0, maxiter=-1, fitspw='', excludechans=False, wtrange=[], flagbackup=True, preview=False, datacolumn='data' )
24-Feb-2026 02:24:35	2026-02-24 02:24:12 INFO statwt::::casa Backup original flags before applying new flags
24-Feb-2026 02:24:35	2026-02-24 02:24:12 INFO AgentFlagger::open Table type is Measurement Set
24-Feb-2026 02:24:35	2026-02-24 02:24:12 INFO FlagVersion::saveFlagVersion Creating new backup flag file called statwt_1
24-Feb-2026 02:24:35	2026-02-24 02:24:12 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.
24-Feb-2026 02:24:35	2026-02-24 02:24:13 INFO StatWtTVI::StatWtTVI Using DATA to compute weights
24-Feb-2026 02:24:35	....10....20....30....40....50....60....70....80....90....100%
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO StatWtTVI::summarizeFlagging Originally, 35.0215% of the data were flagged. StatWtTVI flagged an additional 0%.
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO StatWtTVI::summarizeFlagging TOTAL FLAGGED DATA AFTER RUNNING STATWT: 35.0215%
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO StatWtTVI::summarizeFlagging
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO StatWtTVI::summarizeFlagging SPECTRAL_WINDOW SAMPLES_WITH_NON-ZERO_VARIANCE SAMPLES_WHERE_REAL_PART_VARIANCE_DIFFERS_BY_>50%_FROM_IMAGINARY_PART
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO StatWtTVI::summarizeFlagging 0 997366 260705
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO StatWtTVI::_logUsedChannels Weights are being computed using SPW 0, channels 0~63
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO StatWtTVI::summarizeStats The mean of the computed weights is 321.367
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO StatWtTVI::summarizeStats The variance of the computed weights is 348636
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO StatWtTVI::summarizeStats Weights which had corresponding flags of True prior to running this application were not used to compute these stats.
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO statwt::::casa Task statwt complete. Start time: 2026-02-23 21:24:11.796376 End time: 2026-02-23 21:24:33.675364
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO statwt::::casa ##### End Task: statwt #####
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO statwt::::casa ##########################################
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO tclean::::casa ##########################################
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO tclean::::casa ##### Begin Task: tclean #####
24-Feb-2026 02:24:35	2026-02-24 02:24:34 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, 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 )
24-Feb-2026 02:24:35	2026-02-24 02:24:34 INFO tclean::::casa Verifying Input Parameters
24-Feb-2026 02:24:35	2026-02-24 02:24:36 INFO tclean::::casa setupCluster, Setting up 3 engines.
24-Feb-2026 02:24:35	2026-02-24 02:24:36 INFO SynthesisUtilMethods::continuumDataPartition
24-Feb-2026 02:24:35	2026-02-24 02:24:36 INFO SynthesisUtilMethods::continuumDataPartition + Rows = 511641 767459 [P][M]: 0:2 ROWNUMBER() >= 511641 && ROWNUMBER() <= 767459
24-Feb-2026 02:25:35	2026-02-24 02:24:36 INFO tclean::::casa Partitioned Selection : {'0': {'ms0': {'antenna': '', 'datacolumn': 'corrected', 'field': '', 'freqbeg': '', 'freqend': '', 'freqframe': 'LSRK', 'incrmodel': False, 'msname': '3c391_ctm_mosaic_spw0.ms', 'obs': '', 'outframe': 'LSRK', 'readonly': True, 'scan': '', 'spw': '', 'state': '', 'taql': 'ROWNUMBER() >= 1 && ROWNUMBER() <= 255820', '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() >= 255821 && ROWNUMBER() <= 511640', '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() >= 511641 && ROWNUMBER() <= 767459', 'timestr': '', 'usescratch': False, 'uvdist': ''}}}
24-Feb-2026 02:25:35	2026-02-24 02:24:36 INFO SynthesisUtilMethods::getResource synthesis.imager.memprofile.enable is enabled, initializing output file for imager profiling information (memory and run time): casa.synthesis.imager.memprofile.74663.cvpost135.20260224_022435.txt
24-Feb-2026 02:25:35	2026-02-24 02:24:36 INFO SynthesisImagerVi2::selectData MS : 3c391_ctm_mosaic_spw0.ms \| [Opened in readonly mode]
24-Feb-2026 02:25:35	2026-02-24 02:24:36 INFO SynthesisImagerVi2::selectData NRows selected : 767459
24-Feb-2026 02:25:35	2026-02-24 02:24:37 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
24-Feb-2026 02:25:35	2026-02-24 02:24:37 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
24-Feb-2026 02:25:35	2026-02-24 02:24:38 INFO SynthesisImagerVi2::defineImage Impars: start
24-Feb-2026 02:25:35	2026-02-24 02:24:38 INFO SynthesisImagerVi2::defineImage Shape: [480, 480, 1, 1] Spectral: [4.59835e+09] at [0] with increment [1.27992e+08]
24-Feb-2026 02:25:35	2026-02-24 02:24:38 INFO SynthesisImagerVi2::defineImage Set Gridding options for [3c391_ctm_spw0_multiscale] with ftmachine : mosaicft
24-Feb-2026 02:25:35	2026-02-24 02:24:38 INFO SynthesisImager::getVPRecord Using default Voltage Patterns from the VPManager
24-Feb-2026 02:25:35	2026-02-24 02:24:38 INFO VPSkyJones::VPSkyJones PB used EVLA
24-Feb-2026 02:25:35	2026-02-24 02:24:38 INFO SynthesisImagerVi2::nSubCubeFitInMemory Required memory: 0.1088 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
24-Feb-2026 02:25:35	2026-02-24 02:24:38 INFO SynthesisImagerVi2::weight() Set imaging weights : Briggs weighting: sidelobes will be suppressed over full image
24-Feb-2026 02:25:35	2026-02-24 02:24:46 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:24:46 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:24:46 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:24:46 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:24:46 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:24:46 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:24:46 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:24:46 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:25:35	2026-02-24 02:24:47 INFO tclean::::casa ...done
24-Feb-2026 02:25:35	2026-02-24 02:24:47 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:25:35	2026-02-24 02:24:48 INFO tclean::::casa ...done
24-Feb-2026 02:25:35	2026-02-24 02:24:50 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:25:35	2026-02-24 02:24:51 INFO tclean::::casa ...done
24-Feb-2026 02:25:35	2026-02-24 02:24:53 INFO SynthesisImagerVi2::selectData MS : 3c391_ctm_mosaic_spw0.ms \| [Opened in readonly mode]
24-Feb-2026 02:25:35	2026-02-24 02:24:53 INFO SynthesisImagerVi2::selectData NRows selected : 767459
24-Feb-2026 02:25:35	2026-02-24 02:24:54 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
24-Feb-2026 02:25:35	2026-02-24 02:24:54 INFO SynthesisImagerVi2::defineImage Define image coordinates for [3c391_ctm_spw0_multiscale] :
24-Feb-2026 02:25:35	2026-02-24 02:24:55 INFO SynthesisImagerVi2::defineImage Impars: start
24-Feb-2026 02:25:35	2026-02-24 02:24:55 INFO SynthesisImagerVi2::defineImage Shape: [480, 480, 1, 1] Spectral: [4.59835e+09] at [0] with increment [1.27992e+08]
24-Feb-2026 02:25:35	2026-02-24 02:24:55 INFO SynthesisImagerVi2::defineImage Set Gridding options for [3c391_ctm_spw0_multiscale] with ftmachine : mosaicft
24-Feb-2026 02:25:35	2026-02-24 02:24:55 INFO SynthesisImager::getVPRecord Using default Voltage Patterns from the VPManager
24-Feb-2026 02:25:35	2026-02-24 02:24:55 INFO VPSkyJones::VPSkyJones PB used EVLA
24-Feb-2026 02:25:35	2026-02-24 02:24:55 INFO SynthesisImagerVi2::nSubCubeFitInMemory Required memory: 0.1088 GB. Available mem.: 395.6 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 4.
24-Feb-2026 02:25:35	2026-02-24 02:24:55 INFO SynthesisImagerVi2::weight() Set imaging weights : Briggs weighting: sidelobes will be suppressed over full image
24-Feb-2026 02:25:35	2026-02-24 02:25:04 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:25:04 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:25:04 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:25:04 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:25:04 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:25:04 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:25:04 INFO VisSetUtil::VisImagingWeight() Normal robustness, robust = 0.5
24-Feb-2026 02:25:35	2026-02-24 02:25:04 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:25:35	2026-02-24 02:25:05 INFO tclean::::casa ...done
24-Feb-2026 02:25:35	2026-02-24 02:25:05 INFO task_tclean::SynthesisDeconvolver::setupDeconvolution Set Deconvolution Options for [3c391_ctm_spw0_multiscale] : multiscale
24-Feb-2026 02:25:35	2026-02-24 02:25:05 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:25:35	2026-02-24 02:25:06 INFO tclean::::casa ...done
24-Feb-2026 02:25:35	2026-02-24 02:25:06 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:25:35
24-Feb-2026 02:25:35	0%....10..
24-Feb-2026 02:25:35	0%....10..
24-Feb-2026 02:25:35	0%....10.....20.....20.20.....30.....30...30...40........4040...50........50.50...60........60.60....70........7070....80.......8080......90......100%
24-Feb-2026 02:25:35	..9090.....100%
24-Feb-2026 02:25:35	...100%
24-Feb-2026 02:25:35	2026-02-24 02:25:34 INFO tclean::::casa ...done
24-Feb-2026 02:25:35	2026-02-24 02:25:34 INFO SynthesisNormalizer::gatherImages Gather psf,weight images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:25:35	2026-02-24 02:25:34 INFO SIImageStore::calcSensitivity [3c391_ctm_spw0_multiscale] Theoretical sensitivity (Jy/bm):1.06835e-05
24-Feb-2026 02:25:35	2026-02-24 02:25:34 INFO SIImageStore::getPSFGaussian Time to fit Gaussian to PSF 0.005937
24-Feb-2026 02:25:35	2026-02-24 02:25:34 INFO SIImageStore::printBeamSet Beam : 17.0639 arcsec, 14.9556 arcsec, 21.6316 deg
24-Feb-2026 02:25:35	2026-02-24 02:25:34 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:25:35	2026-02-24 02:25:35 INFO tclean::::casa ...done
24-Feb-2026 02:25:35	2026-02-24 02:25:35 INFO SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:25:35	2026-02-24 02:25:35 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:25:35	2026-02-24 02:25:36 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:25:35	2026-02-24 02:25:36 INFO SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:26:39	2026-02-24 02:25:36 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:26:39	2026-02-24 02:25:36 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:26:39
24-Feb-2026 02:26:39	0%....10...
24-Feb-2026 02:26:39	0%....10....20.
24-Feb-2026 02:26:39	0%....10......20..20.30.........30...40.30..........404050............506050...........60.60.70...........707080...........8080...90.......100%
24-Feb-2026 02:26:39	...9090.....100%
24-Feb-2026 02:26:39	...100%
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO tclean::::casa ...done
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0, over full image : 0.123932
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO SynthesisDeconvolver::setupMask [3c391_ctm_spw0_multiscale] Setting up a mask from [3c391_clean_mask.crtf]
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO CasacRegionManager::fromBCS No directional region specified. Using full positional plane.
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO CasacRegionManager::fromBCS Using all spectral channels.
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO CasacRegionManager::fromBCS Using polarizations ALL
24-Feb-2026 02:26:39	2026-02-24 02:25:57 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.
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO ImageMaskAttacher::makeMask Created and initialized mask `mask0'
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO RegionTextParser::_determineVersion RegionTextParser::_determineVersion: Found spec version 0
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO AsciiRegionFileParser::_parse Combined 1 image regions (which excludes any annotation regions)
24-Feb-2026 02:26:39	2026-02-24 02:25:57 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).
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO SynthesisDeconvolver::setupMask ----------------------------------------------------------------------------------------------------------------------------------------
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO SynthesisDeconvolver::setupMask [3c391_ctm_spw0_multiscale] Number of pixels in the clean mask : 19718 out of a total of 230400 pixels. [ 8.55816 % ]
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.123932, over full image : 0.123932
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 20000, 'cyclethreshold': 0.011826763860881329, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.0118268, CycleNiter=20000, Gain=0.1
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::clean() Initial maximum residual is 0.469931 within the mask
24-Feb-2026 02:26:39	2026-02-24 02:25:57 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:26:39	2026-02-24 02:25:59 INFO MatrixCleaner::clean() 200 0.0712141 2.46328
24-Feb-2026 02:26:39	2026-02-24 02:26:01 INFO MatrixCleaner::clean() 400 0.0422464 3.36933
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO MatrixCleaner::clean() Reached stopping threshold 0.0118268 at iteration 502
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO MatrixCleaner::clean() Optimum flux is 0.0116264
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO MatrixCleaner::clean() 0 0.0627742
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO MatrixCleaner::clean() 1 0.172396
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO MatrixCleaner::clean() 2 3.43351
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO MatrixCleaner::clean() 3 0.0570872
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->503 [503], model=0->3.7246, peakres=0.123932->0.0116265, Reached cyclethreshold.
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 503 iterations.
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:26:39	2026-02-24 02:26:02 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:26:39
24-Feb-2026 02:26:39	0%....
24-Feb-2026 02:26:39	0%....
24-Feb-2026 02:26:39	0%....101010........20......20...2030.........30...40.30..........405040............506050............606070............708070...........8080...90......100%
24-Feb-2026 02:26:39	....9090.....100%
24-Feb-2026 02:26:39	...100%
24-Feb-2026 02:26:39	2026-02-24 02:26:39 INFO tclean::::casa ...done
24-Feb-2026 02:27:19	2026-02-24 02:26:39 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.0142191, over full image : 0.0142191
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 19497, 'cyclethreshold': 0.0013569192960858345, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.00135692, CycleNiter=19497, Gain=0.1
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() Initial maximum residual is 0.0505523 within the mask
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() Diverging due to large scale?
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() 0 0
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() 1 0.0069819
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() 2 0.175678
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO MatrixCleaner::clean() 3 0.0273352
24-Feb-2026 02:27:19	2026-02-24 02:26:40 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
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->49 [49], model=3.72461->3.90727, peakres=0.0142191->0.0117853, Exited multiscale minor cycle without reaching any stopping criterion.
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 552 iterations.
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:27:19	2026-02-24 02:26:40 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:27:19
24-Feb-2026 02:27:19	0%....
24-Feb-2026 02:27:19	0%....
24-Feb-2026 02:27:19	0%....101010........20......20...2030.........30...40.30..........405040............506050............606070............708070...........8080...90......100%
24-Feb-2026 02:27:19	....9090.....100%
24-Feb-2026 02:27:19	...100%
24-Feb-2026 02:27:19	2026-02-24 02:27:17 INFO tclean::::casa ...done
24-Feb-2026 02:27:19	2026-02-24 02:27:17 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.0119035, over full image : 0.0119035
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 19448, 'cyclethreshold': 0.0011359405471011996, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.00113594, CycleNiter=19448, Gain=0.1
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::clean() Initial maximum residual is 0.277388 within the mask
24-Feb-2026 02:27:19	2026-02-24 02:27:18 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:28:16	2026-02-24 02:27:20 INFO MatrixCleaner::clean() 200 0.0256308 0.678161
24-Feb-2026 02:28:16	2026-02-24 02:27:22 INFO MatrixCleaner::clean() 400 0.00678201 1.10411
24-Feb-2026 02:28:16	2026-02-24 02:27:24 INFO MatrixCleaner::clean() 600 0.0073669 1.41764
24-Feb-2026 02:28:16	2026-02-24 02:27:26 INFO MatrixCleaner::clean() 800 0.0139059 1.6712
24-Feb-2026 02:28:16	2026-02-24 02:27:28 INFO MatrixCleaner::clean() 1000 0.00406681 1.8746
24-Feb-2026 02:28:16	2026-02-24 02:27:30 INFO MatrixCleaner::clean() 1200 0.0103415 2.04536
24-Feb-2026 02:28:16	2026-02-24 02:27:32 INFO MatrixCleaner::clean() 1400 0.0031618 2.19537
24-Feb-2026 02:28:16	2026-02-24 02:27:34 INFO MatrixCleaner::clean() 1600 0.0027961 2.3217
24-Feb-2026 02:28:16	2026-02-24 02:27:36 INFO MatrixCleaner::clean() 1800 0.00335221 2.4371
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() 2000 0.00671455 2.53882
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() 0 0.134221
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() 1 0.165437
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() 2 1.95941
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() 3 0.279746
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() Failed to reach stopping threshold
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() Initial maximum residual is 0.00301749 within the mask
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() Diverging due to large scale?
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() 0 0
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() 1 0.000301749
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() 2 0.000671242
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO MatrixCleaner::clean() 3 0
24-Feb-2026 02:28:16	2026-02-24 02:27:39 WARN SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185) MSClean minor cycle stopped at large scale negative or diverging
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->2002 [2002], model=3.90727->6.44637, peakres=0.0119035->0.011904, Possible divergence. Peak residual increased by 10% from minimum.
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 2554 iterations.
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:28:16	2026-02-24 02:27:39 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:28:16
24-Feb-2026 02:28:16	0%....
24-Feb-2026 02:28:16	0%....
24-Feb-2026 02:28:16	0%....101010........20......20...2030.........30...40.30..........405040............506050............606070............807070...........8080...90......100%
24-Feb-2026 02:28:16	....9090.....100%
24-Feb-2026 02:28:16	...100%
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO tclean::::casa ...done
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00333072, over full image : 0.00575185
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 17446, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=17446, Gain=0.1
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() Initial maximum residual is 0.0129891 within the mask
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() Diverging due to large scale?
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() 0 0
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() 1 0
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() 2 0.0170775
24-Feb-2026 02:28:16	2026-02-24 02:28:16 INFO MatrixCleaner::clean() 3 0.00871298
24-Feb-2026 02:29:14	2026-02-24 02:28:16 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
24-Feb-2026 02:29:14	2026-02-24 02:28:16 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->15 [15], model=6.4464->6.46345, peakres=0.00333072->0.00316979, Exited multiscale minor cycle without reaching any stopping criterion.
24-Feb-2026 02:29:14	2026-02-24 02:28:16 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 2569 iterations.
24-Feb-2026 02:29:14	2026-02-24 02:28:16 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:29:14	2026-02-24 02:28:16 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:29:14	2026-02-24 02:28:16 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:29:14	2026-02-24 02:28:16 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:29:14	2026-02-24 02:28:16 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:29:14	2026-02-24 02:28:16 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:29:14
24-Feb-2026 02:29:14	0%....
24-Feb-2026 02:29:14	0%....
24-Feb-2026 02:29:14	0%....101010........20......20...2030.........30...40.30..........405040............506050............606070............807070...........8080...90......100%
24-Feb-2026 02:29:14	....9090.....100%
24-Feb-2026 02:29:14	...100%
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO tclean::::casa ...done
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00317446, over full image : 0.00575647
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 17431, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=17431, Gain=0.1
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:29:14	2026-02-24 02:28:53 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::clean() Initial maximum residual is 0.0875262 within the mask
24-Feb-2026 02:29:14	2026-02-24 02:28:54 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:29:14	2026-02-24 02:28:56 INFO MatrixCleaner::clean() 200 0.0080887 0.259702
24-Feb-2026 02:29:14	2026-02-24 02:28:58 INFO MatrixCleaner::clean() 400 0.00309816 0.42819
24-Feb-2026 02:29:14	2026-02-24 02:29:00 INFO MatrixCleaner::clean() 600 0.00615639 0.557607
24-Feb-2026 02:29:14	2026-02-24 02:29:02 INFO MatrixCleaner::clean() 800 0.00559942 0.652203
24-Feb-2026 02:29:14	2026-02-24 02:29:04 INFO MatrixCleaner::clean() 1000 0.00516054 0.738602
24-Feb-2026 02:29:14	2026-02-24 02:29:06 INFO MatrixCleaner::clean() 1200 0.00479933 0.812358
24-Feb-2026 02:29:14	2026-02-24 02:29:08 INFO MatrixCleaner::clean() 1400 0.00200196 0.882983
24-Feb-2026 02:29:14	2026-02-24 02:29:10 INFO MatrixCleaner::clean() 1600 0.00416925 0.945761
24-Feb-2026 02:29:14	2026-02-24 02:29:12 INFO MatrixCleaner::clean() 1800 0.00395897 0.998534
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() 2000 0.003648 1.04422
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() 0 0.0922141
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() 1 0.053549
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() 2 0.671914
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() 3 0.226538
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() Failed to reach stopping threshold
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() Initial maximum residual is 0.00124119 within the mask
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() Diverging due to large scale?
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() 0 0.000497384
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() 1 0.000164309
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() 2 0.000364221
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO MatrixCleaner::clean() 3 0
24-Feb-2026 02:29:14	2026-02-24 02:29: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
24-Feb-2026 02:29:14	2026-02-24 02:29:14 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->2006 [2006], model=6.46347->7.50831, peakres=0.00317446->0.00317935, Possible divergence. Peak residual increased by 10% from minimum.
24-Feb-2026 02:30:28	2026-02-24 02:29:14 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 4575 iterations.
24-Feb-2026 02:30:28	2026-02-24 02:29:14 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:30:28	2026-02-24 02:29:14 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:30:28	2026-02-24 02:29:14 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:30:28	2026-02-24 02:29:14 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:30:28	2026-02-24 02:29:14 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:30:28	2026-02-24 02:29:14 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:30:28
24-Feb-2026 02:30:28	0%....
24-Feb-2026 02:30:28	0%....
24-Feb-2026 02:30:28	0%....101010........20......20...2030.........30...40.30..........405040............506050............606070............807070...........8080...90......100%
24-Feb-2026 02:30:28	....9090.....100%
24-Feb-2026 02:30:28	...100%
24-Feb-2026 02:30:28	2026-02-24 02:29:51 INFO tclean::::casa ...done
24-Feb-2026 02:30:28	2026-02-24 02:29:51 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00165766, over full image : 0.00417559
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 15425, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=15425, Gain=0.1
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() Initial maximum residual is 0.00612433 within the mask
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() Diverging due to large scale?
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() 0 0
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() 1 0
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() 2 0.00295178
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO MatrixCleaner::clean() 3 0.00431722
24-Feb-2026 02:30:28	2026-02-24 02:29:52 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
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->6 [6], model=7.50835->7.51127, peakres=0.00165766->0.00157954, Exited multiscale minor cycle without reaching any stopping criterion.
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 4581 iterations.
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:30:28	2026-02-24 02:29:52 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:30:28
24-Feb-2026 02:30:28	0%....
24-Feb-2026 02:30:28	0%....10
24-Feb-2026 02:30:28	0%....1010........20......20...2030.........30...40.30..........405040............506050............606070............807070...........8080...90......100%
24-Feb-2026 02:31:18	....9090.....100%
24-Feb-2026 02:31:18	...100%
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO tclean::::casa ...done
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00158005, over full image : 0.00419047
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 15419, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=15419, Gain=0.1
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::clean() Initial maximum residual is 0.0432318 within the mask
24-Feb-2026 02:31:18	2026-02-24 02:30:29 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:31:18	2026-02-24 02:30:31 INFO MatrixCleaner::clean() 200 0.00424115 0.135222
24-Feb-2026 02:31:18	2026-02-24 02:30:33 INFO MatrixCleaner::clean() 400 0.00387344 0.227044
24-Feb-2026 02:31:18	2026-02-24 02:30:35 INFO MatrixCleaner::clean() 600 0.00119473 0.29496
24-Feb-2026 02:31:18	2026-02-24 02:30:37 INFO MatrixCleaner::clean() 800 0.00330578 0.348711
24-Feb-2026 02:31:18	2026-02-24 02:30:40 INFO MatrixCleaner::clean() 1000 0.00308535 0.391707
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO MatrixCleaner::clean() Reached stopping threshold 0.001 at iteration 1172
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO MatrixCleaner::clean() Optimum flux is 0.00099929
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO MatrixCleaner::clean() 0 0.0420299
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO MatrixCleaner::clean() 1 0.0195962
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO MatrixCleaner::clean() 2 0.238396
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO MatrixCleaner::clean() 3 0.130826
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->1173 [1173], model=7.5113->7.942, peakres=0.00158005->0.00099929, Reached cyclethreshold.
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 5754 iterations.
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:31:18	2026-02-24 02:30:41 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:31:18	2026-02-24 02:30:42 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:31:18	2026-02-24 02:30:42 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:31:18
24-Feb-2026 02:31:18	0%....
24-Feb-2026 02:31:18	0%....
24-Feb-2026 02:31:18	0%....101010........20......20...2030.........30...40.30..........504040............506050............606070............807070...........8080...90......100%
24-Feb-2026 02:31:18	....9090.....100%
24-Feb-2026 02:31:18	...100%
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO tclean::::casa ...done
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00115927, over full image : 0.00357428
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 14246, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=14246, Gain=0.1
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:31:18	2026-02-24 02:31:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() Initial maximum residual is 0.00385483 within the mask
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() Diverging due to large scale?
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() 0 0
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() 1 0
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() 2 0.00113737
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO MatrixCleaner::clean() 3 0.00281371
24-Feb-2026 02:31:59	2026-02-24 02:31:19 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
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->4 [4], model=7.94205->7.94314, peakres=0.00115927->0.00114446, Exited multiscale minor cycle without reaching any stopping criterion.
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 5758 iterations.
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:31:59	2026-02-24 02:31:19 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:31:59
24-Feb-2026 02:31:59	0%....
24-Feb-2026 02:31:59	0%....10
24-Feb-2026 02:31:59	0%....1010........20......20...2030.........30...40.30..........405040............506050............606070............807070...........8080...90......100%
24-Feb-2026 02:31:59	....9090.....100%
24-Feb-2026 02:31:59	...100%
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO tclean::::casa ...done
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00114513, over full image : 0.0035814
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 14242, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=14242, Gain=0.1
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::clean() Initial maximum residual is 0.0281607 within the mask
24-Feb-2026 02:31:59	2026-02-24 02:31:56 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:31:59	2026-02-24 02:31:58 INFO MatrixCleaner::clean() 200 0.00302269 0.0904705
24-Feb-2026 02:31:59	2026-02-24 02:31:59 INFO MatrixCleaner::clean() Reached stopping threshold 0.001 at iteration 271
24-Feb-2026 02:31:59	2026-02-24 02:31:59 INFO MatrixCleaner::clean() Optimum flux is 0.000995069
24-Feb-2026 02:31:59	2026-02-24 02:31:59 INFO MatrixCleaner::clean() 0 0.00572049
24-Feb-2026 02:31:59	2026-02-24 02:31:59 INFO MatrixCleaner::clean() 1 0.00290984
24-Feb-2026 02:31:59	2026-02-24 02:31:59 INFO MatrixCleaner::clean() 2 0.0561797
24-Feb-2026 02:31:59	2026-02-24 02:31:59 INFO MatrixCleaner::clean() 3 0.0512952
24-Feb-2026 02:31:59	2026-02-24 02:31:59 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->272 [272], model=7.94319->8.05911, peakres=0.00114513->0.00099507, Reached cyclethreshold.
24-Feb-2026 02:31:59	2026-02-24 02:31:59 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 6030 iterations.
24-Feb-2026 02:33:13	2026-02-24 02:31:59 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:33:13	2026-02-24 02:31:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:33:13	2026-02-24 02:31:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:33:13	2026-02-24 02:31:59 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:33:13	2026-02-24 02:31:59 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:33:13	2026-02-24 02:31:59 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:33:13
24-Feb-2026 02:33:13	0%....10
24-Feb-2026 02:33:13	0%....
24-Feb-2026 02:33:13	0%....1010........20......20...2030.........30...40.30..........405040............506050............606070............807070...........8080...90......100%
24-Feb-2026 02:33:13	....9090.....100%
24-Feb-2026 02:33:13	...100%
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO tclean::::casa ...done
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00103072, over full image : 0.00340742
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 13970, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=13970, Gain=0.1
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() Initial maximum residual is 0.00315795 within the mask
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() Diverging due to large scale?
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() 0 0
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() 1 0
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() 2 0.000315795
24-Feb-2026 02:33:13	2026-02-24 02:32:36 INFO MatrixCleaner::clean() 3 0.00235641
24-Feb-2026 02:33:13	2026-02-24 02:32:36 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
24-Feb-2026 02:33:13	2026-02-24 02:32:37 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->2 [2], model=8.0592->8.05943, peakres=0.00103072->0.0010287, Exited multiscale minor cycle without reaching any stopping criterion.
24-Feb-2026 02:33:13	2026-02-24 02:32:37 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 6032 iterations.
24-Feb-2026 02:33:13	2026-02-24 02:32:37 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:33:13	2026-02-24 02:32:37 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:33:13	2026-02-24 02:32:37 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:33:13	2026-02-24 02:32:37 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:33:13	2026-02-24 02:32:37 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:33:13	2026-02-24 02:32:37 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:33:13
24-Feb-2026 02:33:13	0%....
24-Feb-2026 02:33:13	0%....
24-Feb-2026 02:33:13	0%....101010........20......20...2030.........30...40.30..........405040............506050............606070............807070...........8080...90......100%
24-Feb-2026 02:33:13	....9090.....100%
24-Feb-2026 02:33:13	...100%
24-Feb-2026 02:33:13	2026-02-24 02:33:14 INFO tclean::::casa ...done
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.00102879, over full image : 0.00341047
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO tclean::::casa Minor Cycle controls : {'cycleniter': 13968, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle \| CycleThreshold=0.001, CycleNiter=13968, Gain=0.1
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::validatePsf() Peak of PSF = 1 at [240, 240]
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 0
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 1
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 2
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::makePsfScales() Calculating convolutions for scale 3
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 0
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 1
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 2
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::makeScaleMasks() Calculating mask convolution for scale 3
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::clean() scale 1 = 0 pixels with bias = 1
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::clean() scale 2 = 5 pixels with bias = 0.9
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::clean() scale 3 = 15 pixels with bias = 0.7
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::clean() scale 4 = 45 pixels with bias = 0.1
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::clean() Cleaning using given mask
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::clean() Cleaning pixels with mask values above 0.9
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::clean() Starting iteration
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::clean() Initial maximum residual is 0.0235654 within the mask
24-Feb-2026 02:34:02	2026-02-24 02:33:14 INFO MatrixCleaner::clean() iteration MaximumResidual CleanedFlux
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO MatrixCleaner::clean() Reached stopping threshold 0.001 at iteration 77
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO MatrixCleaner::clean() Optimum flux is 0.000999844
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO MatrixCleaner::clean() 0 0.00202637
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO MatrixCleaner::clean() 1 0.000538492
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO MatrixCleaner::clean() 2 0.0141356
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO MatrixCleaner::clean() 3 0.0160219
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO SDAlgorithmBase::deconvolve [3c391_ctm_spw0_multiscale] iters=0->78 [78], model=8.05951->8.09206, peakres=0.00102879->0.000999846, Reached cyclethreshold.
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO grpcInteractiveCleanManager::mergeCycleExecutionRecord Completed 6110 iterations.
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO task_tclean::SynthesisNormalizer::scatterModel Send the model from : 3c391_ctm_spw0_multiscale to all nodes :[/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3]
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1.model
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2.model
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO task_tclean::SIImageStore::setModelImageOne Copying input model 3c391_ctm_spw0_multiscale.model to /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3.model
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO tclean::::casa ----------------------------- Running Parallel Major Cycle ----------------------------
24-Feb-2026 02:34:02	2026-02-24 02:33:15 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:34:02
24-Feb-2026 02:34:02	0%....
24-Feb-2026 02:34:02	0%....
24-Feb-2026 02:34:02	0%....101010........20......20...2030.........30...40.30..........504040............506050............606070............807070...........8080...90......100%
24-Feb-2026 02:34:02	....9090.....100%
24-Feb-2026 02:34:02	...100%
24-Feb-2026 02:34:02	2026-02-24 02:33:52 INFO tclean::::casa ...done
24-Feb-2026 02:34:02	2026-02-24 02:33:52 INFO SynthesisNormalizer::gatherImages Gather residual images : [/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n1, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n2, /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228/work/test_stk_vla_users_continuum_from_SDM/3c391_ctm_spw0_multiscale.workdirectory/3c391_ctm_spw0_multiscale.n3] onto :3c391_ctm_spw0_multiscale
24-Feb-2026 02:34:02	2026-02-24 02:33:52 INFO task_tclean::SynthesisDeconvolver::initMinorCycle Absolute Peak residual within mask : 0.0010059, over full image : 0.00336881
24-Feb-2026 02:34:02	2026-02-24 02:33:52 INFO tclean::::casa Reached global stopping criterion : threshold
24-Feb-2026 02:34:02	2026-02-24 02:33:52 INFO tclean::::casa getSummary call: fullsummary=False
24-Feb-2026 02:34:02	2026-02-24 02:33:52 INFO SDAlgorithmBase::restore [3c391_ctm_spw0_multiscale] : Restoring model image.
24-Feb-2026 02:34:02	2026-02-24 02:33:52 INFO SIImageStore::restore Beam for chan : 0 : 17.0639 arcsec, 14.9556 arcsec, 21.6316 deg
24-Feb-2026 02:34:02	2026-02-24 02:34:01 INFO tclean::::casa checkJobs. Blocking for nodes to finish
24-Feb-2026 02:34:02	2026-02-24 02:34:02 INFO tclean::::casa ...done
24-Feb-2026 02:34:02	2026-02-24 02:34:02 INFO tclean::::casa Ending use of cluster, but not closing it. Call clustermanager.stop_cluster() to close it if needed.
24-Feb-2026 02:34:02	2026-02-24 02:34:02 INFO tclean::::casa Searching for images with prefix '3c391_ctm_spw0_multiscale'... Found these, writing history into them: ['3c391_ctm_spw0_multiscale.image', '3c391_ctm_spw0_multiscale.gridwt_moswt', '3c391_ctm_spw0_multiscale.psf', '3c391_ctm_spw0_multiscale.sumwt', '3c391_ctm_spw0_multiscale.weight', '3c391_ctm_spw0_multiscale.pb', '3c391_ctm_spw0_multiscale.model', '3c391_ctm_spw0_multiscale.residual', '3c391_ctm_spw0_multiscale.mask']
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO tclean::::casa Task tclean complete. Start time: 2026-02-23 21:24:33.708391 End time: 2026-02-23 21:34:02.614816
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO tclean::::casa ##### End Task: tclean #####
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO tclean::::casa ##########################################
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::::casa ##########################################
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::::casa ##### Begin Task: impbcor #####
24-Feb-2026 02:34:02	2026-02-24 02:34:03 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 )
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::CasacRegionManager::fromBCS No directional region specified. Using full positional plane.
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::CasacRegionManager::fromBCS Using all spectral channels.
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::CasacRegionManager::fromBCS Using polarizations ALL
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::ImageRegion::fromRecord Selected bounding box :
24-Feb-2026 02:34:02	2026-02-24 02:34:03 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)
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::ImageRegion::fromRecord Selected bounding box :
24-Feb-2026 02:34:02	2026-02-24 02:34:03 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)
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::SubImageFactory::createImage Creating image '3c391_ctm_spw0_multiscale.pbcorimage' of shape [480, 480, 1, 1]
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::ImageMaskAttacher::makeMask Created mask `mask0'
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::::casa Task impbcor complete. Start time: 2026-02-23 21:34:02.620579 End time: 2026-02-23 21:34:02.749306
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::::casa ##### End Task: impbcor #####
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO impbcor::::casa ##########################################
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::::casa ##########################################
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::::casa ##### Begin Task: imstat #####
24-Feb-2026 02:34:02	2026-02-24 02:34:03 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 )
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::CasacRegionManager::fromBCS No directional region specified. Using full positional plane.
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::CasacRegionManager::fromBCS Using all spectral channels.
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::CasacRegionManager::fromBCS Using polarizations ALL
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::image::statistics Determining stats for image 3c391_ctm_spw0_multiscale.pbcorimage
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageRegion::fromRecord Selected bounding box :
24-Feb-2026 02:34:02	2026-02-24 02:34:03 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)
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup Statistics calculated using Classic algorithm
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup Regions ---
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (pixel) [blc]: [0, 0, 0, 0]
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (pixel) [trc]: [479, 479, 0, 0]
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (world) [blcf]: 18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (world) [trcf]: 18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: Values ---
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- flux density [flux]: 9.38396 Jy
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- number of points [npts]: 115808
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- maximum value [max]: 0.155533 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- minimum value [min]: -0.00651302 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- position of max value (pixel) [maxpos]: [288, 256, 0, 0]
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- position of min value (pixel) [minpos]: [70, 141, 0, 0]
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- position of max value (world) [maxposf]: 18:49:16.243, -00.55.00.580, I, 4.59835e+09Hz
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- position of min value (world) [minposf]: 18:49:52.582, -00.59.48.073, I, 4.59835e+09Hz
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- Sum of pixel values [sum]: 434.164 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- Sum of squared pixel values [sumsq]: 17.8574 Jy/beam.Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: Statistics ---
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- Mean of the pixel values [mean]: 0.003749 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- Variance of the pixel values : 0.000140144 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- Standard deviation of the Mean [sigma]: 0.0118383 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- Root mean square [rms]: 0.0124177 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- Median of the pixel values [median]: 0.000430752 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- Median of the deviations [medabsdevmed]: 0.00135173 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- IQR [quartile]: 0.00292397 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- First quartile [q1]: -0.000747427 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat:::: -- Third quartile [q3]: 0.00217655 Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics Sum column unit = Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics+ Mean column unit = Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics+ Std_dev column unit = Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics+ Minimum column unit = Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics+ Maximum column unit = Jy/beam
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics Npts Sum Mean Rms Std_dev Minimum Maximum
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics 1.158080e+05 4.341640e+02 3.748999e-03 1.241766e-02 1.183826e-02 -6.513016e-03 1.555329e-01
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::::casa Task imstat complete. Start time: 2026-02-23 21:34:02.754082 End time: 2026-02-23 21:34:02.771459
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::::casa ##### End Task: imstat #####
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::::casa ##########################################
24-Feb-2026 02:34:02	Expected image peak at pixels [288 256], got [288 256], tolerance exact
24-Feb-2026 02:34:02	Expected pbcor image peak of 0.15553903579711914, got 0.15553292632102966, tolerance 4.0%
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::::casa ##########################################
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::::casa ##### Begin Task: imstat #####
24-Feb-2026 02:34:02	2026-02-24 02:34:03 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 )
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::RegionTextParser::_determineVersion RegionTextParser::_determineVersion: Found spec version 0
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::AsciiRegionFileParser::_parse Combined 1 image regions (which excludes any annotation regions)
24-Feb-2026 02:34:02	2026-02-24 02:34:03 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).
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::CasacRegionManager::fromBCS Region read from CRTF file 3c391_clean_mask.crtf
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::image::statistics Determining stats for image 3c391_ctm_spw0_multiscale.residual
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageRegion::fromRecord Selected bounding box :
24-Feb-2026 02:34:02	2026-02-24 02:34:03 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)
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup Statistics calculated using Classic algorithm
24-Feb-2026 02:34:02	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup Regions ---
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (pixel) [blc]: [140, 145, 0, 0]
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (pixel) [trc]: [316, 315, 0, 0]
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup -- bottom-left corner (world) [blcf]: 18:49:40.913, -00.59.38.078, I, 4.59835e+09Hz
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::_logStartup -- top-right corner (world) [trcf]: 18:49:11.576, -00.52.33.079, I, 4.59835e+09Hz
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: Values ---
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- number of points [npts]: 19718
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- maximum value [max]: 0.0010059
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- minimum value [min]: -0.000914995
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- position of max value (pixel) [maxpos]: [288, 231, 0, 0]
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- position of min value (pixel) [minpos]: [151, 157, 0, 0]
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- position of max value (world) [maxposf]: 18:49:16.243, -00.56.03.080, I, 4.59835e+09Hz
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- position of min value (world) [minposf]: 18:49:39.080, -00.59.08.078, I, 4.59835e+09Hz
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- Sum of pixel values [sum]: 8.27909
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- Sum of squared pixel values [sumsq]: 0.00576672
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: Statistics ---
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- Mean of the pixel values [mean]: 0.000419875
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- Variance of the pixel values : 1.16171e-07
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- Standard deviation of the Mean [sigma]: 0.000340838
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- Root mean square [rms]: 0.000540795
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- Median of the pixel values [median]: 0.000458625
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- Median of the deviations [medabsdevmed]: 0.000229459
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- IQR [quartile]: 0.000461812
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- First quartile [q1]: 0.000220054
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat:::: -- Third quartile [q3]: 0.000681866
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics Sum column unit =
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics+ Mean column unit =
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics+ Std_dev column unit =
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics+ Minimum column unit =
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics+ Maximum column unit =
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics Npts Sum Mean Rms Std_dev Minimum Maximum
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::ImageStatsCalculator::statistics 1.971800e+04 8.279089e+00 4.198747e-04 5.407954e-04 3.408384e-04 -9.149949e-04 1.005903e-03
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::::casa Task imstat complete. Start time: 2026-02-23 21:34:02.773698 End time: 2026-02-23 21:34:02.790474
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::::casa ##### End Task: imstat #####
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO imstat::::casa ##########################################
24-Feb-2026 02:34:25	Expected peak residual of 0.0010077510960400105, got 0.0010059026535600424, tolerance 10.0%
24-Feb-2026 02:34:25	Expected rms residual of 0.000542584067811474, got 0.000540795425479754, tolerance 10.0%
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO name:: No table opened.
24-Feb-2026 02:34:25	2026-02-24 02:34:03 INFO name:: No table opened.
24-Feb-2026 02:34:25	.
24-Feb-2026 02:34:25	----------------------------------------------------------------------
24-Feb-2026 02:34:25	Ran 1 test in 1321.490s
24-Feb-2026 02:34:25
24-Feb-2026 02:34:25	OK
24-Feb-2026 02:34:25	Executor result: 0
24-Feb-2026 02:34:25	Failed tests:[]
24-Feb-2026 02:34:25
24-Feb-2026 02:34:25	Results: [{'testname': 'test_regression_alma_M100_if', 'runtime': '0:19:20.700470', 'returncode': 0, 'testerr': []}, {'testname': 'test_stk_srdp_restore_mode', 'runtime': '0:40:58.136976', 'returncode': 0, 'testerr': []}, {'testname': 'test_stk_alma_pipeline_imaging', 'runtime': '1:38:07.823599', 'returncode': 0, 'testerr': []}, {'testname': 'test_stk_vlass_pipeline_imaging', 'runtime': '0:38:28.779322', 'returncode': 0, 'testerr': []}, {'testname': 'test_stk_vla_users_continuum_from_SDM', 'runtime': '0:22:24.667473', 'returncode': 0, 'testerr': []}]
24-Feb-2026 02:34:25	Stopping Xvfb.
24-Feb-2026 02:34:25	Stopping framebuffer <subprocess.Popen object at 0x7faa19cc2d30>
24-Feb-2026 02:34:25	Virtual frame buffer deployed at :22828 with pid 22882 successfully shutdown
24-Feb-2026 02:34:25	Xvfb stopped.
24-Feb-2026 02:34:25	Finished task 'Test' with result: Success
24-Feb-2026 02:34:25	Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
24-Feb-2026 02:34:25	Parsing test results under /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-DEVVERCASALITHPY31223-CCM228...
24-Feb-2026 02:34:25	Finished task 'Parse test results' with result: Success
24-Feb-2026 02:34:25	Running post build plugin 'Docker Container Cleanup'
24-Feb-2026 02:34:25	Running post build plugin 'NCover Results Collector'
24-Feb-2026 02:34:25	Running post build plugin 'Build Results Label Collector'
24-Feb-2026 02:34:25	Running post build plugin 'Clover Results Collector'
24-Feb-2026 02:34:25	Running post build plugin 'npm Cache Cleanup'
24-Feb-2026 02:34:25	Running post build plugin 'Artifact Copier'
24-Feb-2026 02:34:25	Finalising the build...
24-Feb-2026 02:34:25	Stopping timer.
24-Feb-2026 02:34:25	Build CASASPECS-DEVVERCASALITHPY31223-CCM228-2 completed.
23-Feb-2026 21:34:26	Running on server: post build plugin 'Build Hanging Detection Configuration'
23-Feb-2026 21:34:26	Running on server: post build plugin 'NCover Results Collector'
23-Feb-2026 21:34:26	Running on server: post build plugin 'Build Labeller'
23-Feb-2026 21:34:26	Running on server: post build plugin 'Clover Delta Calculator'
23-Feb-2026 21:34:26	Running on server: post build plugin 'Maven Dependencies Postprocessor'
23-Feb-2026 21:34:26	All post build plugins have finished
23-Feb-2026 21:34:26	Generating build results summary...
23-Feb-2026 21:34:26	Saving build results to disk...
23-Feb-2026 21:34:26	Store variable context...
23-Feb-2026 21:34:26	Finished building CASASPECS-DEVVERCASALITHPY31223-CCM228-2.

Build: #2 was successful

Job: Test Casalith Cvpost Many Linux 2.28 was successful

Stages & jobs

Default Stage

Build log

Build #2

Build: #2 was successful

Job: Test Casalith Cvpost Many Linux 2.28 was successful

Stages & jobs

Default Stage

Build log