Build: #44 failed

Job: ZZ - VLA Stakeholder Tests EL7 Docker was successful

Build log

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

09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
09-May-2024 01:15:18 2024-05-09 05:15:18        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   
09-May-2024 01:15:18 2024-05-09 05:15:18        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]
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::solve        ----- PER SPW INFO -----
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::solve        SPW: 0 [1, 1]  [1, 1]        [1, 1]            [1, 1]
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::solve        ----- GLOBAL INFO -----
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::solve        [1, 1]  [1, 1]        [1, 1]            [1, 1]
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        calibrater::solve        Finished solving.
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        bandpass::::casa        Task bandpass complete. Start time: 2024-05-09 05:13:41.646549 End time: 2024-05-09 05:15:17.940157
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        bandpass::::casa        ##### End Task: bandpass             #####
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        bandpass::::casa        ##########################################
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        gaincal::::casa        ##########################################
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        gaincal::::casa        ##### Begin Task: gaincal            #####
09-May-2024 01:15:18 2024-05-09 05:15:18        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 )
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        gaincal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        gaincal::calibrater::open        Opening MS: 3c391_ctm_mosaic_10s_spw0.ms for calibration.
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        gaincal::Calibrater::        Initializing nominal selection to the whole MS.
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        gaincal::::casa        NB: gaincal automatically excludes auto-correlations.
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        calibrater::reset        Reseting solve/apply state
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::selectvis+         Selecting on spw: '0:5~58'
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::selectvis+         Selecting on field: 'J1331+3030'
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1!=ANTENNA2'
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::selectvis        By selection 850358 rows are reduced to 31922
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::selectvis        Frequency selection:
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::selectvis        .  Spw 0:5~58 (54 channels, step by 1)
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1!=ANTENNA2'
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO                 (KAntPos Jones: Overriding with spwmap=[0] since KAntPos Jones is not spw-dependent)
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO                 (KAntPos Jones: Enforcing calWt()=false for phase/delay-like terms)
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO                No VLATrDelCorr keyword in the antpos caltable; turning trop delay correction OFF.
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::setapply(type, applypar)        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:18 2024-05-09 05:15:18        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO                 (K Jones: Enforcing calWt()=false for phase/delay-like terms)
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::setapply(type, applypar)        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::setapply(type, applypar)        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=nearest,linear spwmap=[-1] calWt=true
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::setsolve        Arranging to SOLVE:
09-May-2024 01:15:21 2024-05-09 05:15:18        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
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        calibrater::solve        Beginning solve-----------------------------
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::solve        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::solve        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=nearest,linear spwmap=[-1] calWt=true
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::solve        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::solve        The following calibration term is arranged for solve:
09-May-2024 01:15:21 2024-05-09 05:15:18        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
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1]
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 4599000000
09-May-2024 01:15:21 2024-05-09 05:15:18        INFO        Calibrater::solve        For solint = inf, found 4 solution intervals.
09-May-2024 01:15:21 Found no unflagged data at:   (time=2010/04/24/08:02:21.4 field=0 spw=0 chan=0)
09-May-2024 01:15:21 2024-05-09 05:15:19        INFO        Calibrater::solve          Found good G Jones solutions in 3 solution intervals.
09-May-2024 01:15:21 2024-05-09 05:15:19        INFO                Applying refant: ea21 refantmode = flex (hold alternate refants' phase constant) when refant flagged
09-May-2024 01:15:21 2024-05-09 05:15:19        INFO                Writing solutions to table: 3c391_ctm_mosaic_10s_spw0.G1
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
09-May-2024 01:15:21 2024-05-09 05:15:21        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   
09-May-2024 01:15:21 2024-05-09 05:15:21        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]
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        Calibrater::solve        ----- PER SPW INFO -----
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        Calibrater::solve        SPW: 0 [4, 4]  [3, 3]        [3, 3]            [3, 3]
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        Calibrater::solve        ----- GLOBAL INFO -----
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        Calibrater::solve        [4, 4]  [3, 3]        [3, 3]            [3, 3]
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        calibrater::solve        Finished solving.
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        gaincal::::casa        Calibration solve statistics per spw:  (expected/attempted/succeeded):
09-May-2024 01:15:21 2024-05-09 05:15:21        INFO        gaincal::::casa          Spw 0: 4/3/3
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        gaincal::::casa        Task gaincal complete. Start time: 2024-05-09 05:15:17.951707 End time: 2024-05-09 05:15:21.572210
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        gaincal::::casa        ##### End Task: gaincal              #####
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        gaincal::::casa        ##########################################
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        gaincal::::casa        ##########################################
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        gaincal::::casa        ##### Begin Task: gaincal            #####
09-May-2024 01:15:21 2024-05-09 05:15:22        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 )
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        gaincal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        gaincal::calibrater::open        Opening MS: 3c391_ctm_mosaic_10s_spw0.ms for calibration.
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        gaincal::Calibrater::        Initializing nominal selection to the whole MS.
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        gaincal::::casa        NB: gaincal automatically excludes auto-correlations.
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        calibrater::reset        Reseting solve/apply state
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::selectvis+         Selecting on spw: '0:5~58'
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::selectvis+         Selecting on field: 'J1822-0938'
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1!=ANTENNA2'
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::selectvis        By selection 850358 rows are reduced to 41256
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::selectvis        Frequency selection:
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::selectvis        .  Spw 0:5~58 (54 channels, step by 1)
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1!=ANTENNA2'
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO                 (KAntPos Jones: Overriding with spwmap=[0] since KAntPos Jones is not spw-dependent)
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO                 (KAntPos Jones: Enforcing calWt()=false for phase/delay-like terms)
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO                No VLATrDelCorr keyword in the antpos caltable; turning trop delay correction OFF.
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::setapply(type, applypar)        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO                 (K Jones: Enforcing calWt()=false for phase/delay-like terms)
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::setapply(type, applypar)        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::setapply(type, applypar)        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=true
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
09-May-2024 01:15:21 2024-05-09 05:15:22        INFO        Calibrater::setsolve        Arranging to SOLVE:
09-May-2024 01:15:46 2024-05-09 05:15:22        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
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        calibrater::solve        Beginning solve-----------------------------
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        Calibrater::solve        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        Calibrater::solve        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=true
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        Calibrater::solve        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        Calibrater::solve        The following calibration term is arranged for solve:
09-May-2024 01:15:46 2024-05-09 05:15:22        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
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1]
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 4599000000
09-May-2024 01:15:46 2024-05-09 05:15:22        INFO        Calibrater::solve        For solint = inf, found 14 solution intervals.
09-May-2024 01:15:46 2024-05-09 05:15:25        INFO        Calibrater::solve          Found good G Jones solutions in 14 solution intervals.
09-May-2024 01:15:46 2024-05-09 05:15:25        INFO                Applying refant: ea21 refantmode = flex (hold alternate refants' phase constant) when refant flagged
09-May-2024 01:15:46 2024-05-09 05:15:25        INFO                Appending solutions to table: 3c391_ctm_mosaic_10s_spw0.G1
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
09-May-2024 01:15:46 2024-05-09 05:15:27        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   
09-May-2024 01:15:46 2024-05-09 05:15:27        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]
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        Calibrater::solve        ----- PER SPW INFO -----
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        Calibrater::solve        SPW: 0 [14, 14]  [14, 14]        [14, 14]            [14, 14]
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        Calibrater::solve        ----- GLOBAL INFO -----
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        Calibrater::solve        [14, 14]  [14, 14]        [14, 14]            [14, 14]
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        calibrater::solve        Finished solving.
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        gaincal::::casa        Calibration solve statistics per spw:  (expected/attempted/succeeded):
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        gaincal::::casa          Spw 0: 14/14/14
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        gaincal::::casa        Task gaincal complete. Start time: 2024-05-09 05:15:21.583772 End time: 2024-05-09 05:15:27.328808
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        gaincal::::casa        ##### End Task: gaincal              #####
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        gaincal::::casa        ##########################################
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        fluxscale::::casa        ##########################################
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        fluxscale::::casa        ##### Begin Task: fluxscale          #####
09-May-2024 01:15:46 2024-05-09 05:15:27        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 )
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        fluxscale::calibrater::open        ****Using NEW VI2-driven calibrater tool****
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        fluxscale::calibrater::open        Opening MS: 3c391_ctm_mosaic_10s_spw0.ms for calibration.
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        fluxscale::Calibrater::        Initializing nominal selection to the whole MS.
09-May-2024 01:15:46 2024-05-09 05:15:27        INFO        fluxscale::calibrater::fluxscale        Beginning fluxscale--(MSSelection version)-------
09-May-2024 01:15:46 2024-05-09 05:15:28        INFO        fluxscale::::         Found reference field(s): J1331+3030
09-May-2024 01:15:46 2024-05-09 05:15:28        INFO        fluxscale::::         Found transfer field(s):  J1822-0938
09-May-2024 01:15:46 2024-05-09 05:15:28        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)
09-May-2024 01:15:46 2024-05-09 05:15:28        INFO        fluxscale::Calibrater::fluxscale        Storing result in 3c391_ctm_mosaic_10s_spw0.fluxscale1
09-May-2024 01:15:46 2024-05-09 05:15:28        INFO        fluxscale::::        Writing solutions to table: 3c391_ctm_mosaic_10s_spw0.fluxscale1
09-May-2024 01:15:46 2024-05-09 05:15:38        INFO        fluxscale::::casa        Task fluxscale complete. Start time: 2024-05-09 05:15:27.344905 End time: 2024-05-09 05:15:37.742465
09-May-2024 01:15:46 2024-05-09 05:15:38        INFO        fluxscale::::casa        ##### End Task: fluxscale            #####
09-May-2024 01:15:46 2024-05-09 05:15:38        INFO        fluxscale::::casa        ##########################################
09-May-2024 01:15:46 2024-05-09 05:15:38        INFO        applycal::::casa        ##########################################
09-May-2024 01:15:46 2024-05-09 05:15:38        INFO        applycal::::casa        ##### Begin Task: applycal           #####
09-May-2024 01:15:46 2024-05-09 05:15:38        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 )
09-May-2024 01:15:46 2024-05-09 05:15:38        INFO        applycal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
09-May-2024 01:15:46 2024-05-09 05:15:38        INFO        applycal::calibrater::open        Opening MS: 3c391_ctm_mosaic_10s_spw0.ms for calibration.
09-May-2024 01:15:46 2024-05-09 05:15:38        INFO        applycal::VisSetUtil::addScrCols        Adding CORRECTED_DATA column(s).
09-May-2024 01:15:46 2024-05-09 05:15:45        INFO        applycal::Calibrater::        Initializing nominal selection to the whole MS.
09-May-2024 01:15:46 2024-05-09 05:15:45        INFO        applycal::AgentFlagger::open        Table type is Measurement Set
09-May-2024 01:15:46 2024-05-09 05:15:45        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called applycal_1
09-May-2024 01:15:46 2024-05-09 05:15:46        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
09-May-2024 01:15:46 2024-05-09 05:15:46        INFO        calibrater::reset        Reseting solve/apply state
09-May-2024 01:15:46 2024-05-09 05:15:46        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
09-May-2024 01:15:46 2024-05-09 05:15:46        INFO        Calibrater::selectvis+         Selecting on field: 'J1331+3030'
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO        Calibrater::selectvis        By selection 850358 rows are reduced to 31922
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO                 (KAntPos Jones: Overriding with spwmap=[0] since KAntPos Jones is not spw-dependent)
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO                No VLATrDelCorr keyword in the antpos caltable; turning trop delay correction OFF.
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO        Calibrater::setapply(type, applypar)        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:46 2024-05-09 05:15:47        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=3c391_ctm_mosaic_10s_spw0.fluxscale1 select= interp=nearest spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        Calibrater::setapply(type, applypar)        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        Calibrater::setapply(type, applypar)        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        calibrater::correct        Beginning correct---------------------------
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        Calibrater::correct2 (VI2/VB2)        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        Calibrater::correct2 (VI2/VB2)        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        Calibrater::correct2 (VI2/VB2)        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:47        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=3c391_ctm_mosaic_10s_spw0.fluxscale1 select= interp=nearest spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        calibrater::correct        Finished correcting.
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa        Calibration apply flagging statistics (among calibrateable spws):
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa          Total visibilities selected for correction (ncorr x nchan x nrow summed over spws) = 8172032
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa          Flags:
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa           KAntPos Jones: In: 2210048 / 8172032   (27.044044859344652%) --> Out: 2210048 / 8172032   (27.044044859344652%) (3c391_ctm_mosaic_10s_spw0.antpos)
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa           B Jones: In: 2210048 / 8172032   (27.044044859344652%) --> Out: 2210048 / 8172032   (27.044044859344652%) (3c391_ctm_mosaic_10s_spw0.B0)
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa           K Jones: In: 2210048 / 8172032   (27.044044859344652%) --> Out: 2210048 / 8172032   (27.044044859344652%) (3c391_ctm_mosaic_10s_spw0.K0)
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa           G Jones: In: 2210048 / 8172032   (27.044044859344652%) --> Out: 2210048 / 8172032   (27.044044859344652%) (3c391_ctm_mosaic_10s_spw0.fluxscale1)
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa        Task applycal complete. Start time: 2024-05-09 05:15:37.766995 End time: 2024-05-09 05:15:48.458312
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa        ##### End Task: applycal             #####
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa        ##########################################
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa        ##########################################
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::::casa        ##### Begin Task: applycal           #####
09-May-2024 01:15:51 2024-05-09 05:15:48        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 )
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::calibrater::open        Opening MS: 3c391_ctm_mosaic_10s_spw0.ms for calibration.
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::Calibrater::        Initializing nominal selection to the whole MS.
09-May-2024 01:15:51 2024-05-09 05:15:48        INFO        applycal::AgentFlagger::open        Table type is Measurement Set
09-May-2024 01:15:51 2024-05-09 05:15:49        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called applycal_2
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        calibrater::reset        Reseting solve/apply state
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::selectvis+         Selecting on field: 'J1822-0938'
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::selectvis        By selection 850358 rows are reduced to 41256
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO                 (KAntPos Jones: Overriding with spwmap=[0] since KAntPos Jones is not spw-dependent)
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO                No VLATrDelCorr keyword in the antpos caltable; turning trop delay correction OFF.
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::setapply(type, applypar)        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=3c391_ctm_mosaic_10s_spw0.fluxscale1 select= interp=nearest spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::setapply(type, applypar)        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::setapply(type, applypar)        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        calibrater::correct        Beginning correct---------------------------
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::correct2 (VI2/VB2)        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::correct2 (VI2/VB2)        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::correct2 (VI2/VB2)        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:50        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=3c391_ctm_mosaic_10s_spw0.fluxscale1 select= interp=nearest spwmap=[-1] calWt=false
09-May-2024 01:15:51 2024-05-09 05:15:51        INFO        calibrater::correct        Finished correcting.
09-May-2024 01:15:51 2024-05-09 05:15:51        INFO        applycal::::casa        Calibration apply flagging statistics (among calibrateable spws):
09-May-2024 01:15:51 2024-05-09 05:15:51        INFO        applycal::::casa          Total visibilities selected for correction (ncorr x nchan x nrow summed over spws) = 10561536
09-May-2024 01:15:51 2024-05-09 05:15:51        INFO        applycal::::casa          Flags:
09-May-2024 01:15:51 2024-05-09 05:15:51        INFO        applycal::::casa           KAntPos Jones: In: 3207680 / 10561536   (30.37133992631375%) --> Out: 3207680 / 10561536   (30.37133992631375%) (3c391_ctm_mosaic_10s_spw0.antpos)
09-May-2024 01:15:51 2024-05-09 05:15:51        INFO        applycal::::casa           B Jones: In: 3207680 / 10561536   (30.37133992631375%) --> Out: 3207680 / 10561536   (30.37133992631375%) (3c391_ctm_mosaic_10s_spw0.B0)
09-May-2024 01:15:51 2024-05-09 05:15:51        INFO        applycal::::casa           K Jones: In: 3207680 / 10561536   (30.37133992631375%) --> Out: 3207680 / 10561536   (30.37133992631375%) (3c391_ctm_mosaic_10s_spw0.K0)
09-May-2024 01:18:04 2024-05-09 05:15:51        INFO        applycal::::casa           G Jones: In: 3207680 / 10561536   (30.37133992631375%) --> Out: 3207680 / 10561536   (30.37133992631375%) (3c391_ctm_mosaic_10s_spw0.fluxscale1)
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        applycal::::casa        Task applycal complete. Start time: 2024-05-09 05:15:48.461718 End time: 2024-05-09 05:15:51.634086
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        applycal::::casa        ##### End Task: applycal             #####
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        applycal::::casa        ##########################################
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        applycal::::casa        ##########################################
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        applycal::::casa        ##### Begin Task: applycal           #####
09-May-2024 01:18:04 2024-05-09 05:15:52        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 )
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        applycal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        applycal::calibrater::open        Opening MS: 3c391_ctm_mosaic_10s_spw0.ms for calibration.
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        applycal::Calibrater::        Initializing nominal selection to the whole MS.
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        applycal::AgentFlagger::open        Table type is Measurement Set
09-May-2024 01:18:04 2024-05-09 05:15:52        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called applycal_3
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        calibrater::reset        Reseting solve/apply state
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::selectvis+         Selecting on field: '2~8'
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::selectvis        By selection 850358 rows are reduced to 767459
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO                 (KAntPos Jones: Overriding with spwmap=[0] since KAntPos Jones is not spw-dependent)
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO                No VLATrDelCorr keyword in the antpos caltable; turning trop delay correction OFF.
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::setapply(type, applypar)        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=3c391_ctm_mosaic_10s_spw0.fluxscale1 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::setapply(type, applypar)        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::setapply(type, applypar)        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=false
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        calibrater::correct        Beginning correct---------------------------
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::correct2 (VI2/VB2)        .   KAntPos Jones: table=3c391_ctm_mosaic_10s_spw0.antpos select= interp=linear spwmap=[0] calWt=false
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::correct2 (VI2/VB2)        .   B Jones: table=3c391_ctm_mosaic_10s_spw0.B0 select= interp=linear,linear spwmap=[-1] calWt=false
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::correct2 (VI2/VB2)        .   K Jones: table=3c391_ctm_mosaic_10s_spw0.K0 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:18:04 2024-05-09 05:15:53        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=3c391_ctm_mosaic_10s_spw0.fluxscale1 select= interp=linear spwmap=[-1] calWt=false
09-May-2024 01:18:04 2024-05-09 05:16:25        INFO        calibrater::correct        Finished correcting.
09-May-2024 01:18:04 2024-05-09 05:16:25        INFO        applycal::::casa        Calibration apply flagging statistics (among calibrateable spws):
09-May-2024 01:18:04 2024-05-09 05:16:25        INFO        applycal::::casa          Total visibilities selected for correction (ncorr x nchan x nrow summed over spws) = 196469504
09-May-2024 01:18:04 2024-05-09 05:16:25        INFO        applycal::::casa          Flags:
09-May-2024 01:18:04 2024-05-09 05:16:25        INFO        applycal::::casa           KAntPos Jones: In: 53466624 / 196469504   (27.213701318246315%) --> Out: 53466624 / 196469504   (27.213701318246315%) (3c391_ctm_mosaic_10s_spw0.antpos)
09-May-2024 01:18:04 2024-05-09 05:16:25        INFO        applycal::::casa           B Jones: In: 53466624 / 196469504   (27.213701318246315%) --> Out: 53466624 / 196469504   (27.213701318246315%) (3c391_ctm_mosaic_10s_spw0.B0)
09-May-2024 01:18:04 2024-05-09 05:16:25        INFO        applycal::::casa           K Jones: In: 53466624 / 196469504   (27.213701318246315%) --> Out: 53466624 / 196469504   (27.213701318246315%) (3c391_ctm_mosaic_10s_spw0.K0)
09-May-2024 01:18:04 2024-05-09 05:16:25        INFO        applycal::::casa           G Jones: In: 53466624 / 196469504   (27.213701318246315%) --> Out: 68806656 / 196469504   (35.02154512488615%) (3c391_ctm_mosaic_10s_spw0.fluxscale1)
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        applycal::::casa        Task applycal complete. Start time: 2024-05-09 05:15:51.637249 End time: 2024-05-09 05:17:41.576471
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        applycal::::casa        ##### End Task: applycal             #####
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        applycal::::casa        ##########################################
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        split::::casa        ##########################################
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        split::::casa        ##### Begin Task: split              #####
09-May-2024 01:18:04 2024-05-09 05:17:42        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='' )
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::parseMsSpecParams        Input file name is 3c391_ctm_mosaic_10s_spw0.ms
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::parseMsSpecParams        Data column is CORRECTED
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::parseMsSpecParams        Output file name is 3c391_ctm_mosaic_spw0.ms
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::parseDataSelParams        field selection is 2~8
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::parseDataSelParams        correlation selection is RR,LL
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::colCheckInfo        Adding DATA column to output MS from input CORRECTED_DATA column
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::initDataSelectionParams        Selected Fields Ids are [2, 3, 4, 5, 6, 7, 8]
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::initDataSelectionParams        Selected correlations are [[[0] to [0] with stride [1], length [1], [3] to [3] with stride [1], length [1]]]
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::open        Select data
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformManager::createOutputMSStructure        Create output MS structure
09-May-2024 01:18:04 2024-05-09 05:17:42        INFO        MSTransformDataHandler::makeSelection        767459 out of 850358 rows are going to be considered due to the selection criteria.
09-May-2024 01:18:04 2024-05-09 05:18:05        INFO        split::::casa        Task split complete. Start time: 2024-05-09 05:17:41.586520 End time: 2024-05-09 05:18:04.853433
09-May-2024 01:18:04 2024-05-09 05:18:05        INFO        split::::casa        ##### End Task: split                #####
09-May-2024 01:21:01 2024-05-09 05:18:05        INFO        split::::casa        ##########################################
09-May-2024 01:21:01 2024-05-09 05:18:05        INFO        statwt::::casa        ##########################################
09-May-2024 01:21:01 2024-05-09 05:18:05        INFO        statwt::::casa        ##### Begin Task: statwt             #####
09-May-2024 01:21:01 2024-05-09 05:18:05        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' )
09-May-2024 01:21:01 2024-05-09 05:18:05        INFO        statwt::::casa        Backup original flags before applying new flags
09-May-2024 01:21:01 2024-05-09 05:18:05        INFO        AgentFlagger::open        Table type is Measurement Set
09-May-2024 01:21:01 2024-05-09 05:18:05        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called statwt_1
09-May-2024 01:21:01 2024-05-09 05:18:06        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.
09-May-2024 01:21:01 2024-05-09 05:18:07        INFO        StatWtTVI::StatWtTVI        Using DATA to compute weights
09-May-2024 01:21:01 ....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:21:01 2024-05-09 05:18:36        INFO        StatWtTVI::summarizeFlagging        Originally, 35.0215% of the data were flagged. StatWtTVI flagged an additional 0%.
09-May-2024 01:21:01 2024-05-09 05:18:36        INFO        StatWtTVI::summarizeFlagging        TOTAL FLAGGED DATA AFTER RUNNING STATWT: 35.0215%
09-May-2024 01:21:01 2024-05-09 05:18:36        INFO        StatWtTVI::summarizeFlagging        
09-May-2024 01:21:01 2024-05-09 05:18:36        INFO        StatWtTVI::summarizeFlagging        SPECTRAL_WINDOW SAMPLES_WITH_NON-ZERO_VARIANCE SAMPLES_WHERE_REAL_PART_VARIANCE_DIFFERS_BY_>50%_FROM_IMAGINARY_PART
09-May-2024 01:21:01 2024-05-09 05:18:36        INFO        StatWtTVI::summarizeFlagging                      0                         997366                                                               260703
09-May-2024 01:21:01 2024-05-09 05:18:36        INFO        StatWtTVI::_logUsedChannels        Weights are being computed using SPW 0, channels 0~63
09-May-2024 01:21:01 2024-05-09 05:18:41        INFO        StatWtTVI::summarizeStats        The mean of the computed weights is 321.367
09-May-2024 01:21:01 2024-05-09 05:18:41        INFO        StatWtTVI::summarizeStats        The variance of the computed weights is 348637
09-May-2024 01:21:01 2024-05-09 05:18:41        INFO        StatWtTVI::summarizeStats        Weights which had corresponding flags of True prior to running this application were not used to compute these stats.
09-May-2024 01:21:01 2024-05-09 05:19:09        INFO        statwt::::casa        Task statwt complete. Start time: 2024-05-09 05:18:04.943321 End time: 2024-05-09 05:19:08.753304
09-May-2024 01:21:01 2024-05-09 05:19:09        INFO        statwt::::casa        ##### End Task: statwt               #####
09-May-2024 01:21:01 2024-05-09 05:19:09        INFO        statwt::::casa        ##########################################
09-May-2024 01:21:01 2024-05-09 05:19:09        INFO        tclean::::casa        ##########################################
09-May-2024 01:21:01 2024-05-09 05:19:09        INFO        tclean::::casa        ##### Begin Task: tclean             #####
09-May-2024 01:21:01 2024-05-09 05:19:09        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=False )
09-May-2024 01:21:01 2024-05-09 05:19:09        INFO        tclean::::casa        Verifying Input Parameters
09-May-2024 01:21:01 2024-05-09 05:19:09        INFO        SynthesisImagerVi2::selectData         MS : 3c391_ctm_mosaic_spw0.ms | [Opened in readonly mode]
09-May-2024 01:21:01 2024-05-09 05:19:09        INFO        SynthesisImagerVi2::selectData           NRows selected : 767459
09-May-2024 01:21:01 2024-05-09 05:19:10        INFO        SynthesisImagerVi2::defineImage         Define image coordinates for [3c391_ctm_spw0_multiscale] :
09-May-2024 01:21:01 2024-05-09 05:19:11        INFO        SynthesisImagerVi2::defineImage         Impars: start
09-May-2024 01:21:01 2024-05-09 05:19:11        INFO        SynthesisImagerVi2::defineImage         Shape: [480, 480, 1, 1] Spectral: [4.59835e+09] at [0] with increment [1.27992e+08]
09-May-2024 01:21:01 2024-05-09 05:19:11        INFO        SynthesisImagerVi2::defineImage         Set Gridding options for [3c391_ctm_spw0_multiscale] with ftmachine: mosaicft
09-May-2024 01:21:01 2024-05-09 05:19:11        INFO        SynthesisImager::getVPRecord         Using default Voltage Patterns from the VPManager
09-May-2024 01:21:01 2024-05-09 05:19:11        INFO        VPSkyJones::VPSkyJones        PB used EVLA
09-May-2024 01:21:01 2024-05-09 05:19:11        INFO        SynthesisImagerVi2::nSubCubeFitInMemory        Required memory: 0.02472 GB. Available mem.: 46.69 GB (rc, mem. fraction: 80%, memory: -) => Subcubes: 1. Processes on node: 1.
09-May-2024 01:21:01 2024-05-09 05:19:11        INFO        SynthesisImagerVi2::weight()         Set imaging weights : Briggs weighting: sidelobes will be suppressed over full image
09-May-2024 01:21:01 2024-05-09 05:19:24        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
09-May-2024 01:21:01 2024-05-09 05:19:24        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
09-May-2024 01:21:01 2024-05-09 05:19:24        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
09-May-2024 01:21:01 2024-05-09 05:19:24        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
09-May-2024 01:21:01 2024-05-09 05:19:24        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
09-May-2024 01:21:01 2024-05-09 05:19:24        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
09-May-2024 01:21:01 2024-05-09 05:19:24        INFO        VisSetUtil::VisImagingWeight()         Normal robustness, robust = 0.5
09-May-2024 01:21:01 2024-05-09 05:19:24        INFO        task_tclean::SynthesisDeconvolver::setupDeconvolution         Set Deconvolution Options for [3c391_ctm_spw0_multiscale] : multiscale
09-May-2024 01:21:01 2024-05-09 05:19:24        INFO        task_tclean::SynthesisImager::makePSF         ----------------------------------------------------------- Make PSF ---------------------------------------------
09-May-2024 01:21:01 2024-05-09 05:19:25        INFO        task_tclean::HetArrConvFunc::findConvFunction         convolution function support: [6]
09-May-2024 01:21:01
09-May-2024 01:21:01 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:21:01 2024-05-09 05:20:27        INFO        task_tclean::SIImageStore::calcSensitivity         [3c391_ctm_spw0_multiscale] Theoretical sensitivity (Jy/bm):1.06835e-05
09-May-2024 01:21:01 2024-05-09 05:20:27        INFO        task_tclean::SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.014128
09-May-2024 01:21:01 2024-05-09 05:20:27        INFO        task_tclean::SIImageStore::printBeamSet         Beam : 17.0639 arcsec, 14.9556 arcsec, 21.6315 deg
09-May-2024 01:21:01 2024-05-09 05:20:29        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 1 -------------------------------------
09-May-2024 01:21:01
09-May-2024 01:21:01 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:21:01 2024-05-09 05:21:00        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.123931, over full image : 0.123931
09-May-2024 01:21:01 2024-05-09 05:21:00        INFO        SynthesisDeconvolver::setupMask         [3c391_ctm_spw0_multiscale] Setting up a mask from [3c391_clean_mask.crtf]
09-May-2024 01:21:01 2024-05-09 05:21:01        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
09-May-2024 01:21:01 2024-05-09 05:21:01        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
09-May-2024 01:21:01 2024-05-09 05:21:01        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
09-May-2024 01:22:34 2024-05-09 05:21:01        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.
09-May-2024 01:22:34 2024-05-09 05:21:01        INFO        ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
09-May-2024 01:22:34 2024-05-09 05:21:01        INFO        RegionTextParser::_determineVersion        RegionTextParser::_determineVersion: Found spec version 0
09-May-2024 01:22:34 2024-05-09 05:21:01        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
09-May-2024 01:22:34 2024-05-09 05:21:01        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).
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        SynthesisDeconvolver::setupMask         ----------------------------------------------------------------------------------------------------------------------------------------
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        SynthesisDeconvolver::setupMask         [3c391_ctm_spw0_multiscale] Number of pixels in the clean mask : 19718 out of a total of 230400 pixels. [ 8.55816 % ]
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.123931, over full image : 0.123931
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 20000, 'cyclethreshold': 0.011826690286397934, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.0118267, CycleNiter=20000, Gain=0.1
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.469931 within the mask
09-May-2024 01:22:34 2024-05-09 05:21:02        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:22:34 2024-05-09 05:21:03        INFO        MatrixCleaner::clean()         200      0.0711923      2.46328
09-May-2024 01:22:34 2024-05-09 05:21:03        INFO        MatrixCleaner::clean()         400      0.0143844      3.3664
09-May-2024 01:22:34 2024-05-09 05:21:04        INFO        MatrixCleaner::clean()         Reached stopping threshold 0.0118267 at iteration 505
09-May-2024 01:22:34 2024-05-09 05:21:04        INFO        MatrixCleaner::clean()         Optimum flux is 0.0117667
09-May-2024 01:22:34 2024-05-09 05:21:04        INFO        MatrixCleaner::clean()           0    0.0641232
09-May-2024 01:22:34 2024-05-09 05:21:04        INFO        MatrixCleaner::clean()           1    0.170624
09-May-2024 01:22:34 2024-05-09 05:21:04        INFO        MatrixCleaner::clean()           2    3.44998
09-May-2024 01:22:34 2024-05-09 05:21:04        INFO        MatrixCleaner::clean()           3    0.0269819
09-May-2024 01:22:34 2024-05-09 05:21:04        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->506 [506], model=0->3.71053, peakres=0.123931->0.0117667, Reached cyclethreshold.
09-May-2024 01:22:34 2024-05-09 05:21:04        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 506 iterations.
09-May-2024 01:22:34 2024-05-09 05:21:04        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 2 -------------------------------------
09-May-2024 01:22:34
09-May-2024 01:22:34 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0139507, over full image : 0.0139507
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 19494, 'cyclethreshold': 0.0013313068775460124, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.00133131, CycleNiter=19494, Gain=0.1
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0489674 within the mask
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()         Diverging due to large scale?
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()           0    0.00117514
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()           1    0.0082739
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()           2    0.257526
09-May-2024 01:22:34 2024-05-09 05:21:50        INFO        MatrixCleaner::clean()           3    0.0260557
09-May-2024 01:22:34 2024-05-09 05:21:50        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
09-May-2024 01:22:34 2024-05-09 05:21:51        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->74 [74], model=3.71053->3.97751, peakres=0.0139507->0.0111876, Exited multiscale minor cycle without reaching any stopping criterion.
09-May-2024 01:22:34 2024-05-09 05:21:51        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 580 iterations.
09-May-2024 01:22:34 2024-05-09 05:21:51        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 3 -------------------------------------
09-May-2024 01:22:34
09-May-2024 01:22:34 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:22:34 2024-05-09 05:22:34        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0113207, over full image : 0.0113207
09-May-2024 01:22:34 2024-05-09 05:22:34        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 19420, 'cyclethreshold': 0.0010803290642797947, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': False}
09-May-2024 01:24:12 2024-05-09 05:22:34        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.00108033, CycleNiter=19420, Gain=0.1
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.264782 within the mask
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:24:12 2024-05-09 05:22:35        INFO        MatrixCleaner::clean()         200      0.0247378      0.650537
09-May-2024 01:24:12 2024-05-09 05:22:36        INFO        MatrixCleaner::clean()         400      0.0193002      1.07716
09-May-2024 01:24:12 2024-05-09 05:22:37        INFO        MatrixCleaner::clean()         600      0.01604      1.39109
09-May-2024 01:24:12 2024-05-09 05:22:38        INFO        MatrixCleaner::clean()         800      0.0136018      1.6325
09-May-2024 01:24:12 2024-05-09 05:22:38        INFO        MatrixCleaner::clean()         1000      0.00400557      1.8299
09-May-2024 01:24:12 2024-05-09 05:22:39        INFO        MatrixCleaner::clean()         1200      0.0103785      1.99584
09-May-2024 01:24:12 2024-05-09 05:22:40        INFO        MatrixCleaner::clean()         1400      0.00310122      2.15143
09-May-2024 01:24:12 2024-05-09 05:22:40        INFO        MatrixCleaner::clean()         1600      0.00819021      2.27799
09-May-2024 01:24:12 2024-05-09 05:22:41        INFO        MatrixCleaner::clean()         1800      0.00330391      2.3953
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         2000      0.00665728      2.49534
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()           0    0.134322
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()           1    0.16396
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()           2    1.90705
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()           3    0.290007
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         Failed to reach stopping threshold
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00666545 within the mask
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()         Diverging due to large scale?
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()           0    0.00200717
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()           1    0.00295596
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()           2    0.00988213
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        MatrixCleaner::clean()           3    0.0049277
09-May-2024 01:24:12 2024-05-09 05:22:42        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
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->2047 [2047], model=3.97751->6.48769, peakres=0.0113207->0.0113616, Possible divergence. Peak residual increased by 10% from minimum.
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 2627 iterations.
09-May-2024 01:24:12 2024-05-09 05:22:42        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 4 -------------------------------------
09-May-2024 01:24:12
09-May-2024 01:24:12 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:24:12 2024-05-09 05:23:28        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00327078, over full image : 0.00571088
09-May-2024 01:24:12 2024-05-09 05:23:28        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 17373, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=17373, Gain=0.1
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0126381 within the mask
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()         Diverging due to large scale?
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()           0    0
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()           1    0
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()           2    0.00863577
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        MatrixCleaner::clean()           3    0.00891231
09-May-2024 01:24:12 2024-05-09 05:23:29        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
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->8 [8], model=6.4877->6.49633, peakres=0.00327078->0.00327028, Exited multiscale minor cycle without reaching any stopping criterion.
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 2635 iterations.
09-May-2024 01:24:12 2024-05-09 05:23:29        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 5 -------------------------------------
09-May-2024 01:24:12
09-May-2024 01:24:12 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00327234, over full image : 0.00571062
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 17365, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=17365, Gain=0.1
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:25:06 2024-05-09 05:24:12        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0893603 within the mask
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:25:06 2024-05-09 05:24:13        INFO        MatrixCleaner::clean()         200      0.0079734      0.266398
09-May-2024 01:25:06 2024-05-09 05:24:14        INFO        MatrixCleaner::clean()         400      0.00675548      0.434201
09-May-2024 01:25:06 2024-05-09 05:24:15        INFO        MatrixCleaner::clean()         600      0.00598062      0.55692
09-May-2024 01:25:06 2024-05-09 05:24:15        INFO        MatrixCleaner::clean()         800      0.00549948      0.651525
09-May-2024 01:25:06 2024-05-09 05:24:16        INFO        MatrixCleaner::clean()         1000      -0.00171996      0.72711
09-May-2024 01:25:06 2024-05-09 05:24:17        INFO        MatrixCleaner::clean()         1200      0.00159623      0.803626
09-May-2024 01:25:06 2024-05-09 05:24:17        INFO        MatrixCleaner::clean()         1400      0.00438573      0.871637
09-May-2024 01:25:06 2024-05-09 05:24:18        INFO        MatrixCleaner::clean()         1600      0.00407823      0.930808
09-May-2024 01:25:06 2024-05-09 05:24:19        INFO        MatrixCleaner::clean()         1800      0.00130511      0.983919
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         2000      0.00122694      1.03362
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()           0    0.0918628
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()           1    0.0522158
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()           2    0.654316
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()           3    0.235225
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         Failed to reach stopping threshold
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00122614 within the mask
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()         Diverging due to large scale?
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()           0    0.000245195
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()           1    0.000161956
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()           2    0.000359443
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        MatrixCleaner::clean()           3    0
09-May-2024 01:25:06 2024-05-09 05:24:20        WARN        SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185)        MSClean minor cycle stopped at large scale negative or diverging
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->2004 [2004], model=6.49633->7.53034, peakres=0.00327234->0.00327097, Possible divergence. Peak residual increased by 10% from minimum.
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 4639 iterations.
09-May-2024 01:25:06 2024-05-09 05:24:20        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 6 -------------------------------------
09-May-2024 01:25:06
09-May-2024 01:25:06 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:25:06 2024-05-09 05:25:06        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0016393, over full image : 0.00413543
09-May-2024 01:25:06 2024-05-09 05:25:06        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 15361, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
09-May-2024 01:25:06 2024-05-09 05:25:06        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=15361, Gain=0.1
09-May-2024 01:25:06 2024-05-09 05:25:06        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:25:06 2024-05-09 05:25:06        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:25:06 2024-05-09 05:25:06        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00621045 within the mask
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()         Diverging due to large scale?
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()           0    0
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()           1    0
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()           2    0.00121795
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        MatrixCleaner::clean()           3    0.00438063
09-May-2024 01:25:06 2024-05-09 05:25:07        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
09-May-2024 01:25:06 2024-05-09 05:25:07        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->3 [3], model=7.53036->7.53155, peakres=0.0016393->0.00163317, Exited multiscale minor cycle without reaching any stopping criterion.
09-May-2024 01:27:29 2024-05-09 05:25:07        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 4642 iterations.
09-May-2024 01:27:29 2024-05-09 05:25:07        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 7 -------------------------------------
09-May-2024 01:27:29
09-May-2024 01:27:29 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00163326, over full image : 0.00414509
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 15358, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=15358, Gain=0.1
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0438489 within the mask
09-May-2024 01:27:29 2024-05-09 05:25:53        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:27:29 2024-05-09 05:25:54        INFO        MatrixCleaner::clean()         200      0.00418398      0.136103
09-May-2024 01:27:29 2024-05-09 05:25:54        INFO        MatrixCleaner::clean()         400      0.00377445      0.227276
09-May-2024 01:27:29 2024-05-09 05:25:55        INFO        MatrixCleaner::clean()         600      0.00343884      0.290844
09-May-2024 01:27:29 2024-05-09 05:25:56        INFO        MatrixCleaner::clean()         800      0.00109922      0.345267
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        MatrixCleaner::clean()         1000      0.00301683      0.39056
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        MatrixCleaner::clean()         Reached stopping threshold 0.001 at iteration 1104
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        MatrixCleaner::clean()         Optimum flux is 0.000998469
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        MatrixCleaner::clean()           0    0.0406308
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        MatrixCleaner::clean()           1    0.016712
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        MatrixCleaner::clean()           2    0.223277
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        MatrixCleaner::clean()           3    0.131362
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->1105 [1105], model=7.53158->7.94341, peakres=0.00163326->0.000998469, Reached cyclethreshold.
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 5747 iterations.
09-May-2024 01:27:29 2024-05-09 05:25:57        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 8 -------------------------------------
09-May-2024 01:27:29
09-May-2024 01:27:29 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00114855, over full image : 0.00358516
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 14253, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=14253, Gain=0.1
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00395045 within the mask
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()         Diverging due to large scale?
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()           0    0
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()           1    0
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()           2    0.000395045
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        MatrixCleaner::clean()           3    0.00284111
09-May-2024 01:27:29 2024-05-09 05:26:43        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
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->2 [2], model=7.94346->7.94381, peakres=0.00114855->0.00113967, Exited multiscale minor cycle without reaching any stopping criterion.
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 5749 iterations.
09-May-2024 01:27:29 2024-05-09 05:26:43        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 9 -------------------------------------
09-May-2024 01:27:29
09-May-2024 01:27:29 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:27:29 2024-05-09 05:27:29        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00113956, over full image : 0.00358922
09-May-2024 01:27:29 2024-05-09 05:27:29        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 14251, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
09-May-2024 01:27:29 2024-05-09 05:27:29        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=14251, Gain=0.1
09-May-2024 01:27:29 2024-05-09 05:27:29        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:27:29 2024-05-09 05:27:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:27:29 2024-05-09 05:27:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:27:29 2024-05-09 05:27:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:27:29 2024-05-09 05:27:29        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0284249 within the mask
09-May-2024 01:28:58 2024-05-09 05:27:29        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:28:58 2024-05-09 05:27:30        INFO        MatrixCleaner::clean()         200      0.00103593      0.0937072
09-May-2024 01:28:58 2024-05-09 05:27:30        INFO        MatrixCleaner::clean()         Reached stopping threshold 0.001 at iteration 271
09-May-2024 01:28:58 2024-05-09 05:27:30        INFO        MatrixCleaner::clean()         Optimum flux is 0.000997252
09-May-2024 01:28:58 2024-05-09 05:27:30        INFO        MatrixCleaner::clean()           0    0.00640544
09-May-2024 01:28:58 2024-05-09 05:27:30        INFO        MatrixCleaner::clean()           1    0.00221157
09-May-2024 01:28:58 2024-05-09 05:27:30        INFO        MatrixCleaner::clean()           2    0.0561623
09-May-2024 01:28:58 2024-05-09 05:27:30        INFO        MatrixCleaner::clean()           3    0.0467698
09-May-2024 01:28:58 2024-05-09 05:27:31        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->272 [272], model=7.94386->8.05522, peakres=0.00113956->0.000997252, Reached cyclethreshold.
09-May-2024 01:28:58 2024-05-09 05:27:31        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 6021 iterations.
09-May-2024 01:28:58 2024-05-09 05:27:31        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 10 -------------------------------------
09-May-2024 01:28:58
09-May-2024 01:28:58 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00104006, over full image : 0.0034257
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 13979, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=13979, Gain=0.1
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00316736 within the mask
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()         Diverging due to large scale?
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()           0    0
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()           1    0
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()           2    0.00125343
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        MatrixCleaner::clean()           3    0.00233559
09-May-2024 01:28:58 2024-05-09 05:28:15        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
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->5 [5], model=8.05531->8.05648, peakres=0.00104006->0.00103253, Exited multiscale minor cycle without reaching any stopping criterion.
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 6026 iterations.
09-May-2024 01:28:58 2024-05-09 05:28:15        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 11 -------------------------------------
09-May-2024 01:28:58
09-May-2024 01:28:58 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00103287, over full image : 0.00342524
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 13974, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=13974, Gain=0.1
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.00308735 within the mask
09-May-2024 01:28:58 2024-05-09 05:28:58        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:28:58 2024-05-09 05:28:59        INFO        MatrixCleaner::clean()         Diverging due to large scale?
09-May-2024 01:28:58 2024-05-09 05:28:59        INFO        MatrixCleaner::clean()           0    0
09-May-2024 01:28:58 2024-05-09 05:28:59        INFO        MatrixCleaner::clean()           1    0
09-May-2024 01:28:58 2024-05-09 05:28:59        INFO        MatrixCleaner::clean()           2    0.00215844
09-May-2024 01:28:58 2024-05-09 05:28:59        INFO        MatrixCleaner::clean()           3    0.00230878
09-May-2024 01:30:40 2024-05-09 05:28:59        WARN        SDAlgorithmMSClean::takeOneStep (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SDAlgorithmMSClean.cc, line 185)        MSClean minor cycle stopped at large scale negative or diverging
09-May-2024 01:30:40 2024-05-09 05:28:59        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->8 [8], model=8.05656->8.05862, peakres=0.00103287->0.00103332, Exited multiscale minor cycle without reaching any stopping criterion.
09-May-2024 01:30:40 2024-05-09 05:28:59        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 6034 iterations.
09-May-2024 01:30:40 2024-05-09 05:28:59        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 12 -------------------------------------
09-May-2024 01:30:40
09-May-2024 01:30:40 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.0010342, over full image : 0.00342635
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        tclean::::casa        Minor Cycle controls : {'cycleniter': 13966, 'cyclethreshold': 0.0010000000474974513, 'loopgain': 0.10000000149011612, 'nsigma': 0.0, 'thresholdreached': True}
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] Run multiscale minor-cycle | CycleThreshold=0.001, CycleNiter=13966, Gain=0.1
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::validatePsf()         Peak of PSF = 1 at [240, 240]
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 0
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 1
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 2
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::makePsfScales()         Calculating convolutions for scale 3
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 0
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 1
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 2
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::makeScaleMasks()         Calculating mask convolution for scale 3
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::clean()         scale 1 = 0 pixels with bias = 1
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::clean()         scale 2 = 5 pixels with bias = 0.9
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::clean()         scale 3 = 15 pixels with bias = 0.7
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::clean()         scale 4 = 45 pixels with bias = 0.1
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::clean()         Cleaning using given mask
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::clean()         Cleaning pixels with mask values above 0.9
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::clean()         Starting iteration
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::clean()         Initial maximum residual is 0.0231065 within the mask
09-May-2024 01:30:40 2024-05-09 05:29:42        INFO        MatrixCleaner::clean()         iteration    MaximumResidual   CleanedFlux
09-May-2024 01:30:40 2024-05-09 05:29:43        INFO        MatrixCleaner::clean()         Reached stopping threshold 0.001 at iteration 64
09-May-2024 01:30:40 2024-05-09 05:29:43        INFO        MatrixCleaner::clean()         Optimum flux is 0.000997338
09-May-2024 01:30:40 2024-05-09 05:29:43        INFO        MatrixCleaner::clean()           0    0.00143445
09-May-2024 01:30:40 2024-05-09 05:29:43        INFO        MatrixCleaner::clean()           1    0.000535089
09-May-2024 01:30:40 2024-05-09 05:29:43        INFO        MatrixCleaner::clean()           2    0.0123357
09-May-2024 01:30:40 2024-05-09 05:29:43        INFO        MatrixCleaner::clean()           3    0.0138111
09-May-2024 01:30:40 2024-05-09 05:29:43        INFO        SDAlgorithmBase::deconvolve         [3c391_ctm_spw0_multiscale] iters=0->65 [65], model=8.05872->8.08667, peakres=0.0010342->0.000997338, Reached cyclethreshold.
09-May-2024 01:30:40 2024-05-09 05:29:43        INFO        grpcInteractiveCleanManager::mergeCycleExecutionRecord         Completed 6099 iterations.
09-May-2024 01:30:40 2024-05-09 05:29:43        INFO        task_tclean::SynthesisImager::executeMajorCycle         ----------------------------------------------------------- Run Major Cycle 13 -------------------------------------
09-May-2024 01:30:40
09-May-2024 01:30:40 0%....10....20....30....40....50....60....70....80....90....100%
09-May-2024 01:30:40 2024-05-09 05:30:31        INFO        task_tclean::SynthesisDeconvolver::initMinorCycle          Absolute Peak residual within mask : 0.00100101, over full image : 0.00338761
09-May-2024 01:30:40 2024-05-09 05:30:31        INFO        tclean::::casa        Reached global stopping criterion : threshold
09-May-2024 01:30:40 2024-05-09 05:30:31        INFO        tclean::::casa        getSummary call: fullsummary=False
09-May-2024 01:30:40 2024-05-09 05:30:31        INFO        SDAlgorithmBase::restore         [3c391_ctm_spw0_multiscale] : Restoring model image.
09-May-2024 01:30:40 2024-05-09 05:30:33        INFO        SIImageStore::restore         Beam for chan : 0 : 17.0639 arcsec, 14.9556 arcsec, 21.6315 deg
09-May-2024 01:30:40 2024-05-09 05:30:34        INFO        tclean::::casa        Searching for images with prefix '3c391_ctm_spw0_multiscale'... Found these, writing history into them: ['3c391_ctm_spw0_multiscale.residual', '3c391_ctm_spw0_multiscale.weight', '3c391_ctm_spw0_multiscale.psf', '3c391_ctm_spw0_multiscale.sumwt', '3c391_ctm_spw0_multiscale.mask', '3c391_ctm_spw0_multiscale.image', '3c391_ctm_spw0_multiscale.pb', '3c391_ctm_spw0_multiscale.model']
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        tclean::::casa        Task tclean complete. Start time: 2024-05-09 05:19:08.852984 End time: 2024-05-09 05:30:38.273023
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        tclean::::casa        ##### End Task: tclean               #####
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        tclean::::casa        ##########################################
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        impbcor::::casa        ##########################################
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        impbcor::::casa        ##### Begin Task: impbcor            #####
09-May-2024 01:30:40 2024-05-09 05:30:38        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 )
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        impbcor::CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        impbcor::CasacRegionManager::fromBCS        Using all spectral channels.
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        impbcor::CasacRegionManager::fromBCS        Using polarizations ALL
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        impbcor::ImageRegion::fromRecord        Selected bounding box :
09-May-2024 01:30:40 2024-05-09 05:30:38        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)
09-May-2024 01:30:40 2024-05-09 05:30:38        INFO        impbcor::ImageRegion::fromRecord        Selected bounding box :
09-May-2024 01:30:40 2024-05-09 05:30:38        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)
09-May-2024 01:30:40 2024-05-09 05:30:39        INFO        impbcor::SubImageFactory::createImage        Creating image '3c391_ctm_spw0_multiscale.pbcorimage' of shape [480, 480, 1, 1]
09-May-2024 01:30:40 2024-05-09 05:30:39        INFO        impbcor::ImageMaskAttacher::makeMask        Created mask `mask0'
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        impbcor::::casa        Task impbcor complete. Start time: 2024-05-09 05:30:38.302465 End time: 2024-05-09 05:30:40.330852
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        impbcor::::casa        ##### End Task: impbcor              #####
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        impbcor::::casa        ##########################################
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::casa        ##########################################
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::casa        ##### Begin Task: imstat             #####
09-May-2024 01:30:40 2024-05-09 05:30:40        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 )
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::CasacRegionManager::fromBCS        Using all spectral channels.
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::CasacRegionManager::fromBCS        Using polarizations ALL
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::image::statistics         Determining stats for image 3c391_ctm_spw0_multiscale.pbcorimage
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageRegion::fromRecord        Selected bounding box :
09-May-2024 01:30:40 2024-05-09 05:30:40        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)
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup        Statistics calculated using Classic algorithm
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup        Regions ---
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup                 -- bottom-left corner (pixel) [blc]:  [0, 0, 0, 0]
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup                 -- top-right corner (pixel) [trc]:    [479, 479, 0, 0]
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup                 -- bottom-left corner (world) [blcf]: 18:50:04.251, -01.05.40.567, I, 4.59835e+09Hz
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup                 -- top-right corner (world) [trcf]:   18:48:44.407, -00.45.43.065, I, 4.59835e+09Hz
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::        Values ---
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- flux density [flux]:                    9.37956 Jy
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- number of points [npts]:                115808
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- maximum value [max]:                    0.155521 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- minimum value [min]:                    -0.00646346 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- position of max value (pixel) [maxpos]: [288, 256, 0, 0]
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- position of min value (pixel) [minpos]: [70, 141, 0, 0]
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- position of max value (world) [maxposf]: 18:49:16.243, -00.55.00.579, I, 4.59835e+09Hz
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- position of min value (world) [minposf]: 18:49:52.582, -00.59.48.073, I, 4.59835e+09Hz
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- Sum of pixel values [sum]:               433.96 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- Sum of squared pixel values [sumsq]:     17.849 Jy/beam.Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::        Statistics ---
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Mean of the pixel values [mean]:         0.00374724 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Variance of the pixel values :           0.000140085 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Standard deviation of the Mean [sigma]:  0.0118358 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Root mean square [rms]:                  0.0124147 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Median of the pixel values [median]:     0.000433366 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Median of the deviations [medabsdevmed]: 0.00135403 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- IQR [quartile]:                          0.00292659 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- First quartile [q1]:                     -0.00074787 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Third quartile [q3]:                     0.00217872 Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics        Sum column unit = Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics+        Mean column unit = Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics+        Std_dev column unit = Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics+        Minimum column unit = Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics+        Maximum column unit = Jy/beam
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics        Npts          Sum           Mean          Rms           Std_dev       Minimum       Maximum     
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics         1.158080e+05  4.339601e+02  3.747238e-03  1.241475e-02  1.183577e-02 -6.463460e-03  1.555206e-01
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::casa        Task imstat complete. Start time: 2024-05-09 05:30:40.352065 End time: 2024-05-09 05:30:40.448944
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::casa        ##### End Task: imstat               #####
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::casa        ##########################################
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::casa        ##########################################
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::casa        ##### Begin Task: imstat             #####
09-May-2024 01:30:40 2024-05-09 05:30:40        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 )
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::RegionTextParser::_determineVersion        RegionTextParser::_determineVersion: Found spec version 0
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
09-May-2024 01:30:40 2024-05-09 05:30:40        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).
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::CasacRegionManager::fromBCS        Region read from CRTF file 3c391_clean_mask.crtf
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::image::statistics         Determining stats for image 3c391_ctm_spw0_multiscale.residual
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageRegion::fromRecord        Selected bounding box :
09-May-2024 01:30:40 2024-05-09 05:30:40        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)
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup        Statistics calculated using Classic algorithm
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup        Regions ---
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup                 -- bottom-left corner (pixel) [blc]:  [140, 145, 0, 0]
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup                 -- top-right corner (pixel) [trc]:    [316, 315, 0, 0]
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup                 -- bottom-left corner (world) [blcf]: 18:49:40.913, -00.59.38.078, I, 4.59835e+09Hz
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::_logStartup                 -- top-right corner (world) [trcf]:   18:49:11.576, -00.52.33.079, I, 4.59835e+09Hz
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::        Values ---
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- number of points [npts]:                19718
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- maximum value [max]:                    0.00100101
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- minimum value [min]:                    -0.000943352
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- position of max value (pixel) [maxpos]: [259, 210, 0, 0]
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- position of min value (pixel) [minpos]: [292, 235, 0, 0]
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- position of max value (world) [maxposf]: 18:49:21.077, -00.56.55.580, I, 4.59835e+09Hz
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- position of min value (world) [minposf]: 18:49:15.576, -00.55.53.079, I, 4.59835e+09Hz
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- Sum of pixel values [sum]:               8.31612
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                 -- Sum of squared pixel values [sumsq]:     0.0058081
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::        Statistics ---
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Mean of the pixel values [mean]:         0.000421753
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Variance of the pixel values :           1.16689e-07
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Standard deviation of the Mean [sigma]:  0.000341597
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Root mean square [rms]:                  0.000542732
09-May-2024 01:30:40 2024-05-09 05:30:40        INFO        imstat::::                -- Median of the pixel values [median]:     0.000459087
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::::                -- Median of the deviations [medabsdevmed]: 0.000229437
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::::                -- IQR [quartile]:                          0.000460837
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::::                -- First quartile [q1]:                     0.000223755
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::::                -- Third quartile [q3]:                     0.000684591
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics        Sum column unit =
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics+        Mean column unit =
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics+        Std_dev column unit =
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics+        Minimum column unit =
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics+        Maximum column unit =
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics        Npts          Sum           Mean          Rms           Std_dev       Minimum       Maximum     
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::ImageStatsCalculator::statistics         1.971800e+04  8.316120e+00  4.217527e-04  5.427321e-04  3.415973e-04 -9.433521e-04  1.001009e-03
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::::casa        Task imstat complete. Start time: 2024-05-09 05:30:40.456363 End time: 2024-05-09 05:30:40.495890
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::::casa        ##### End Task: imstat               #####
09-May-2024 01:30:58 2024-05-09 05:30:40        INFO        imstat::::casa        ##########################################
09-May-2024 01:30:58 2024-05-09 05:30:41        INFO        name::        No table opened.
09-May-2024 01:30:58 2024-05-09 05:30:41        INFO        name::        No table opened.
09-May-2024 01:30:58
09-May-2024 01:30:58 Using configuration file ~/.casa/config.py
09-May-2024 01:30:58
09-May-2024 01:30:58 Using matplotlib backend: TkAgg
09-May-2024 01:30:58 CASA 6.6.4.24 -- Common Astronomy Software Applications [6.6.4.24]
09-May-2024 01:30:58 Expected flag percentage of 20.558870499248552, got 20.558870499248552, tolerance 1.0
09-May-2024 01:30:58 Expected 850358 rows in split MS, got 850358, tolerance exact
09-May-2024 01:30:58 Expected 14 nonzero antenna position corrections, got 14, tolerance exact
09-May-2024 01:30:58 Expected minimum antenna position correction of -0.0257, got -0.025699999183416367, tolerance 1e-05
09-May-2024 01:30:58 Expected maximum antenna position correction of 0.0045, got 0.0044999998062849045, tolerance 1e-05
09-May-2024 01:30:58 Expected 46 input fluxboot solutions, got 46.0, tolerance exact
09-May-2024 01:30:58 Expected J1822-0938 bootstrapped flux of 2.2973563472684653, got 2.2973640066011796, tolerance 4.0%
09-May-2024 01:30:58 Expected image peak at pixels [288 256], got [288 256], tolerance exact
09-May-2024 01:30:58 Expected pbcor image peak of 0.15553903579711914, got 0.15552055835723877, tolerance 4.0%
09-May-2024 01:30:58 Expected peak residual of 0.0010077510960400105, got 0.001001008553430438, tolerance 10.0%
09-May-2024 01:30:58 Expected rms residual of 0.000542584067811474, got 0.0005427321003670183, tolerance 10.0%
09-May-2024 01:30:58 .
09-May-2024 01:30:58 ----------------------------------------------------------------------
09-May-2024 01:30:58 Ran 1 test in 4843.701s
09-May-2024 01:30:58
09-May-2024 01:30:58 OK
09-May-2024 01:30:58 Executor result: 0
09-May-2024 01:30:58 Failed tests:[]
09-May-2024 01:30:58
09-May-2024 01:30:58 Results: [{'testname': 'test_stk_vla_users_continuum_from_SDM', 'runtime': '1:20:57.050663', 'returncode': 0, 'testerr': []}]
09-May-2024 01:30:58 Stopping Xvfb.
09-May-2024 01:30:58 Stopping framebuffer <Popen: returncode: None args: ['Xvfb', ':155', '-screen', '0', '2048x2048x2...>
09-May-2024 01:30:58 Virtual frame buffer deployed at :155 with pid 167 successfully shutdown
09-May-2024 01:30:58 Xvfb stopped.
09-May-2024 01:49:36 cp: cannot stat '/home/casatest/work/test_stk_vla_users_continuum_from_SDM/*.png': No such file or directory
09-May-2024 01:49:38 total 1188436
09-May-2024 01:49:38 -rw-r--r-- 1 casaci    15001 1216944624 May  9 04:03 casa-6.6.4-24-py3.8.el7.tar.xz
09-May-2024 01:49:38 drwxr-xr-x 2 casaci root           4096 May  9 05:49 html
09-May-2024 01:49:38 -rw-r--r-- 1 casaci casatest        389 May  9 05:30 xUnit-suite-mpi-4.xml
09-May-2024 01:49:38 total 12
09-May-2024 01:49:38 -rw-r--r-- 1 casaci root 11851 May  9 05:49 index.html
09-May-2024 01:49:38 Finished task 'Run VLA tests with casalith on EL7' with result: Success
09-May-2024 01:49:38 Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
09-May-2024 01:49:38 Parsing test results under /export/home/cbt-el7-9/bamboohome/xml-data/build-dir/CASA-CMTS-CRTED...
09-May-2024 01:49:38 Finished task 'Parse test results' with result: Success
09-May-2024 01:49:38 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
09-May-2024 01:49:38
Beginning to execute external process for build 'CASA - CMake Master Test Stakeholder - VLA Stakeholder Tests EL7 Docker #44 (CASA-CMTS-CRTED-44)'
... running command line:
/export/home/cbt-el7-9/bamboohome/temp/CASA-CMTS-CRTED-44-ScriptBuildTask-7327782936872188868.sh
... in: /export/home/cbt-el7-9/bamboohome/xml-data/build-dir/CASA-CMTS-CRTED
09-May-2024 01:49:38 Starting cleanup
09-May-2024 01:49:52 test-container
09-May-2024 01:49:55 test-container
09-May-2024 01:49:55 Cleanup complete
09-May-2024 01:49:55 Finished task 'Cleanup' with result: Success
09-May-2024 01:49:55 Running post build plugin 'Docker Container Cleanup'
09-May-2024 01:49:55 Running post build plugin 'NCover Results Collector'
09-May-2024 01:49:55 Running post build plugin 'Build Results Label Collector'
09-May-2024 01:49:55 Running post build plugin 'Clover Results Collector'
09-May-2024 01:49:55 Running post build plugin 'npm Cache Cleanup'
09-May-2024 01:49:55 Running post build plugin 'Artifact Copier'
09-May-2024 01:49:55 Publishing an artifact: VLA Stakeholder
09-May-2024 01:49:55 Finished publishing of artifact Non required job artifact Http Compression On : [VLA Stakeholder], patterns: [html/**] anchored at: [dockershare] in 175.8 ms
09-May-2024 01:49:55 Finalising the build...
09-May-2024 01:49:55 Stopping timer.
09-May-2024 01:49:55 Build CASA-CMTS-CRTED-44 completed.
09-May-2024 01:49:55 Running on server: post build plugin 'Build Hanging Detection Configuration'
09-May-2024 01:49:55 Running on server: post build plugin 'NCover Results Collector'
09-May-2024 01:49:55 Running on server: post build plugin 'Build Labeller'
09-May-2024 01:49:55 Running on server: post build plugin 'Clover Delta Calculator'
09-May-2024 01:49:55 Running on server: post build plugin 'Maven Dependencies Postprocessor'
09-May-2024 01:49:55 All post build plugins have finished
09-May-2024 01:49:55 Generating build results summary...
09-May-2024 01:49:55 Saving build results to disk...
09-May-2024 01:49:55 Store variable context...
09-May-2024 01:49:55 Finished building CASA-CMTS-CRTED-44.