Build: #294 failed

Job: Test Tasks Many Linux 2.28 CasaShell Rocky 8.10 did not complete

Build log

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

17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::setapply(type, applypar)        .   Accor Jones: table=cal.B select= interp=linear spwmap=[-1] calWt=true
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        .   Accor Jones: table=cal.B select= interp=linear spwmap=[-1] calWt=true
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1]
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 1413421909.70627
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        For solint = inf, found 7 solution intervals.
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        Beginning solution interval 1/7 (~14% complete)
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        Beginning solution interval 2/7 (~28% complete)
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        Beginning solution interval 3/7 (~42% complete)
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        Beginning solution interval 4/7 (~57% complete)
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        Beginning solution interval 5/7 (~71% complete)
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve        Beginning solution interval 6/7 (~85% complete)
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO        Calibrater::solve          Found good Accor Jones solutions in 7 solution intervals.
17-Sep-2026 20:09:11 2026-09-18 00:09:12        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:12 2026-09-18 00:09:13        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    ANT: 26    ANT: 27   
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        SPW: 0 [0, 0]  [7, 7]        [0, 0]            [0, 0]
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        [0, 0]  [7, 7]        [0, 0]            [0, 0]
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:11.722723 End time: 2026-09-18 00:09:12.649639
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::::casa        accor( vis='vla_copy.ms', caltable='cal.C', field='', spw='', intent='', selectdata=True, timerange='', antenna='', scan='', observation='', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=['cal.B'], gainfield=[], interp=['nearest'], spwmap=[] )
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::calibrater::open        Opening MS: vla_copy.ms for calibration.
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::selectvis        By selection 22653 rows are reduced to 1619
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::setapply(type, applypar)        .   Accor Jones: table=cal.B select= interp=nearest spwmap=[-1] calWt=true
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.C append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        .   Accor Jones: table=cal.B select= interp=nearest spwmap=[-1] calWt=true
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        .   Accor Jones: table=cal.C append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1]
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 1413421909.70627
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        For solint = inf, found 7 solution intervals.
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        Beginning solution interval 1/7 (~14% complete)
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        Beginning solution interval 2/7 (~28% complete)
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        Beginning solution interval 3/7 (~42% complete)
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        Beginning solution interval 4/7 (~57% complete)
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        Beginning solution interval 5/7 (~71% complete)
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve        Beginning solution interval 6/7 (~85% complete)
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO        Calibrater::solve          Found good Accor Jones solutions in 7 solution intervals.
17-Sep-2026 20:09:12 2026-09-18 00:09:13        INFO                Writing solutions to table: cal.C
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:13 2026-09-18 00:09:14        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    ANT: 26    ANT: 27   
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        SPW: 0 [0, 0]  [7, 7]        [0, 0]            [0, 0]
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        [0, 0]  [7, 7]        [0, 0]            [0, 0]
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:12.652827 End time: 2026-09-18 00:09:13.711790
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        rmtables( tablenames=['cal.C'] )
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        Removing cal.C
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:13.717377 End time: 2026-09-18 00:09:13.733945
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:13.735256 End time: 2026-09-18 00:09:13.750216
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        rmtables( tablenames=['cal.B'] )
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        Removing cal.B
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:13.751043 End time: 2026-09-18 00:09:13.764605
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:13 .2026-09-18 00:09:14        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        accor::::casa        accor( vis='uid_copy.ms', caltable='cal.A', field='', spw='', intent='', selectdata=True, timerange='', antenna='', scan='', observation='', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=[], gainfield=[], interp=[], spwmap=[] )
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        accor::calibrater::open        Opening MS: uid_copy.ms for calibration.
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::selectvis        By selection 1080 rows are reduced to 540
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        .   (None)
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 84368488281.1505
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 86213458984.275
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        For solint = inf, found 9 solution intervals.
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        Beginning solution interval 1/9 (~11% complete)
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        Beginning solution interval 2/9 (~22% complete)
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        Beginning solution interval 3/9 (~33% complete)
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        Beginning solution interval 4/9 (~44% complete)
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        Beginning solution interval 5/9 (~55% complete)
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        Beginning solution interval 6/9 (~66% complete)
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        Beginning solution interval 7/9 (~77% complete)
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve        Beginning solution interval 8/9 (~88% complete)
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO        Calibrater::solve          Found good Accor Jones solutions in 9 solution intervals.
17-Sep-2026 20:09:13 2026-09-18 00:09:14        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2   
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        SPW: 0 [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        SPW: 1 [0, 0]  [6, 6]        [0, 0]            [0, 0]
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        [0, 0]  [9, 9]        [0, 0]            [0, 0]
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:13.767859 End time: 2026-09-18 00:09:14.625172
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:14.626691 End time: 2026-09-18 00:09:14.645627
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:14 .2026-09-18 00:09:15        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        accor::::casa        accor( vis='VLBA_copy.ms', caltable='cal.A', field='', spw='', intent='', selectdata=True, timerange='', antenna='', scan='', observation='0', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=[], gainfield=[], interp=[], spwmap=[] )
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::selectvis+         Selecting by observation IDs: '0'
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 6280
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        .   (None)
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        For solint = inf, found 6 solution intervals.
17-Sep-2026 20:09:14 2026-09-18 00:09:15        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        Beginning solution interval 1/6 (~16% complete)
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        Beginning solution interval 2/6 (~33% complete)
17-Sep-2026 20:09:14 2026-09-18 00:09:15        INFO        Calibrater::solve        Beginning solution interval 3/6 (~50% complete)
17-Sep-2026 20:09:15 2026-09-18 00:09:15        INFO        Calibrater::solve        Beginning solution interval 4/6 (~66% complete)
17-Sep-2026 20:09:15 2026-09-18 00:09:15        INFO        Calibrater::solve        Beginning solution interval 5/6 (~83% complete)
17-Sep-2026 20:09:15 2026-09-18 00:09:15        INFO        Calibrater::solve          Found good Accor Jones solutions in 6 solution intervals.
17-Sep-2026 20:09:15 2026-09-18 00:09:15        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        SPW: 0 [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        SPW: 1 [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        [0, 0]  [6, 6]        [0, 0]            [0, 0]
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:14.649622 End time: 2026-09-18 00:09:16.397821
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:16.399205 End time: 2026-09-18 00:09:16.417377
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:16 .2026-09-18 00:09:16        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        accor::::casa        accor( vis='VLBA_copy.ms', caltable='cal.A', field='', spw='', intent='', selectdata=True, timerange='', antenna='', scan='2', observation='', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=[], gainfield=[], interp=[], spwmap=[] )
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::selectvis+         Selecting on scan: '2'
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 1200
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        .   (None)
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
17-Sep-2026 20:09:16 2026-09-18 00:09:16        INFO        Calibrater::solve        For solint = inf, found 2 solution intervals.
17-Sep-2026 20:09:16 2026-09-18 00:09:17        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
17-Sep-2026 20:09:16 2026-09-18 00:09:17        INFO        Calibrater::solve        Beginning solution interval 1/2 (~50% complete)
17-Sep-2026 20:09:16 2026-09-18 00:09:17        INFO        Calibrater::solve          Found good Accor Jones solutions in 2 solution intervals.
17-Sep-2026 20:09:16 2026-09-18 00:09:17        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        SPW: 0 [0, 0]  [1, 1]        [0, 0]            [0, 0]
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        SPW: 1 [0, 0]  [1, 1]        [0, 0]            [0, 0]
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        [0, 0]  [2, 2]        [0, 0]            [0, 0]
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:16.421274 End time: 2026-09-18 00:09:17.583706
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:17.587696 End time: 2026-09-18 00:09:17.607941
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:17 .2026-09-18 00:09:18        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        accor::::casa        accor( vis='uid_copy.ms', caltable='cal.A', field='', spw='', intent='', selectdata=False, timerange='', antenna='', scan='2', observation='', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=[], gainfield=[], interp=[], spwmap=[] )
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        accor::calibrater::open        Opening MS: uid_copy.ms for calibration.
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::selectvis        By selection 1080 rows are reduced to 540
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        .   (None)
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 84368488281.1505
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 86213458984.275
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        For solint = inf, found 9 solution intervals.
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        Beginning solution interval 1/9 (~11% complete)
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        Beginning solution interval 2/9 (~22% complete)
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        Beginning solution interval 3/9 (~33% complete)
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        Beginning solution interval 4/9 (~44% complete)
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        Beginning solution interval 5/9 (~55% complete)
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        Beginning solution interval 6/9 (~66% complete)
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        Beginning solution interval 7/9 (~77% complete)
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve        Beginning solution interval 8/9 (~88% complete)
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO        Calibrater::solve          Found good Accor Jones solutions in 9 solution intervals.
17-Sep-2026 20:09:17 2026-09-18 00:09:18        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2   
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        SPW: 0 [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        SPW: 1 [0, 0]  [6, 6]        [0, 0]            [0, 0]
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        [0, 0]  [9, 9]        [0, 0]            [0, 0]
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:17.612309 End time: 2026-09-18 00:09:18.675574
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:18.678859 End time: 2026-09-18 00:09:18.696475
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::::casa        accor( vis='uid_copy.ms', caltable='cal.A', field='', spw='', intent='', selectdata=True, timerange='', antenna='', scan='2', observation='', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=[], gainfield=[], interp=[], spwmap=[] )
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::calibrater::open        Opening MS: uid_copy.ms for calibration.
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::selectvis+         Selecting on scan: '2'
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::selectvis        By selection 1080 rows are reduced to 180
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        .   (None)
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 84368488281.1505
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 86213458984.275
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        For solint = inf, found 2 solution intervals.
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve        Beginning solution interval 1/2 (~50% complete)
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO        Calibrater::solve          Found good Accor Jones solutions in 2 solution intervals.
17-Sep-2026 20:09:18 2026-09-18 00:09:19        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2   
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        SPW: 0 [0, 0]  [1, 1]        [0, 0]            [0, 0]
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        SPW: 1 [0, 0]  [1, 1]        [0, 0]            [0, 0]
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        [0, 0]  [2, 2]        [0, 0]            [0, 0]
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:18.699527 End time: 2026-09-18 00:09:19.650519
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:19.653516 End time: 2026-09-18 00:09:19.670668
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:19.671638 End time: 2026-09-18 00:09:19.672055
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:19 .2026-09-18 00:09:20        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        accor::::casa        accor( vis='VLBA_copy.ms', caltable='cal.A', field='', spw='', intent='', selectdata=True, timerange='', antenna='', scan='', observation='', msselect='', solint='10s', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=[], gainfield=[], interp=[], spwmap=[] )
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 6280
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=10s refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        .   (None)
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=10s refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        For solint = 10s, found 126 solution intervals.
17-Sep-2026 20:09:19 2026-09-18 00:09:20        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        Beginning solution interval 13/126 (~10% complete)
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        Beginning solution interval 26/126 (~20% complete)
17-Sep-2026 20:09:19 2026-09-18 00:09:20        INFO        Calibrater::solve        Beginning solution interval 39/126 (~30% complete)
17-Sep-2026 20:09:20 2026-09-18 00:09:20        INFO        Calibrater::solve        Beginning solution interval 52/126 (~41% complete)
17-Sep-2026 20:09:20 2026-09-18 00:09:20        INFO        Calibrater::solve        Beginning solution interval 65/126 (~51% complete)
17-Sep-2026 20:09:20 2026-09-18 00:09:20        INFO        Calibrater::solve        Beginning solution interval 78/126 (~61% complete)
17-Sep-2026 20:09:20 2026-09-18 00:09:20        INFO        Calibrater::solve        Beginning solution interval 91/126 (~72% complete)
17-Sep-2026 20:09:20 2026-09-18 00:09:20        INFO        Calibrater::solve        Beginning solution interval 104/126 (~82% complete)
17-Sep-2026 20:09:20 2026-09-18 00:09:20        INFO        Calibrater::solve        Beginning solution interval 117/126 (~92% complete)
17-Sep-2026 20:09:20 2026-09-18 00:09:20        INFO        Calibrater::solve          Found good Accor Jones solutions in 126 solution intervals.
17-Sep-2026 20:09:20 2026-09-18 00:09:20        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        SPW: 0 [0, 0]  [63, 63]        [0, 0]            [0, 0]
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        SPW: 1 [0, 0]  [63, 63]        [0, 0]            [0, 0]
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        [0, 0]  [126, 126]        [0, 0]            [0, 0]
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:19.675504 End time: 2026-09-18 00:09:21.375009
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:21.379978 End time: 2026-09-18 00:09:21.398211
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:21 .2026-09-18 00:09:21        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        accor::::casa        accor( vis='uid_copy.ms', caltable='cal.A', field='', spw='1', intent='', selectdata=True, timerange='', antenna='', scan='', observation='', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=[], gainfield=[], interp=[], spwmap=[] )
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        accor::calibrater::open        Opening MS: uid_copy.ms for calibration.
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::selectvis+         Selecting on spw: '1'
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::selectvis        By selection 1080 rows are reduced to 405
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::selectvis        Frequency selection:
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::selectvis        .  Spw 1:0~0 (1 channels, step by 1)
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        .   (None)
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 86213458984.275
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        For solint = inf, found 3 solution intervals.
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        Beginning solution interval 1/3 (~33% complete)
17-Sep-2026 20:09:21 2026-09-18 00:09:21        INFO        Calibrater::solve        Beginning solution interval 2/3 (~66% complete)
17-Sep-2026 20:09:21 2026-09-18 00:09:22        INFO        Calibrater::solve          Found good Accor Jones solutions in 3 solution intervals.
17-Sep-2026 20:09:21 2026-09-18 00:09:22        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2   
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]        [0, 0]            [0, 0]
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        SPW: 1 [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:21.401922 End time: 2026-09-18 00:09:22.189570
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:22.192750 End time: 2026-09-18 00:09:22.209013
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:22 .2026-09-18 00:09:22        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        accor::::casa        accor( vis='VLBA_copy.ms', caltable='cal.B', field='', spw='', intent='', selectdata=True, timerange='', antenna='', scan='', observation='', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=[], gainfield=[], interp=[], spwmap=[] )
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 6280
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.B append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        .   (None)
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        .   Accor Jones: table=cal.B append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        For solint = inf, found 6 solution intervals.
17-Sep-2026 20:09:22 2026-09-18 00:09:22        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        Beginning solution interval 1/6 (~16% complete)
17-Sep-2026 20:09:22 2026-09-18 00:09:22        INFO        Calibrater::solve        Beginning solution interval 2/6 (~33% complete)
17-Sep-2026 20:09:22 2026-09-18 00:09:23        INFO        Calibrater::solve        Beginning solution interval 3/6 (~50% complete)
17-Sep-2026 20:09:22 2026-09-18 00:09:23        INFO        Calibrater::solve        Beginning solution interval 4/6 (~66% complete)
17-Sep-2026 20:09:22 2026-09-18 00:09:23        INFO        Calibrater::solve        Beginning solution interval 5/6 (~83% complete)
17-Sep-2026 20:09:22 2026-09-18 00:09:23        INFO        Calibrater::solve          Found good Accor Jones solutions in 6 solution intervals.
17-Sep-2026 20:09:22 2026-09-18 00:09:23        INFO                Writing solutions to table: cal.B
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        SPW: 0 [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        SPW: 1 [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        [0, 0]  [6, 6]        [0, 0]            [0, 0]
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:22.212522 End time: 2026-09-18 00:09:23.549447
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::::casa        accor( vis='VLBA_copy.ms', caltable='cal.A', field='', spw='', intent='', selectdata=True, timerange='', antenna='', scan='', observation='', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=['cal.B'], gainfield=[], interp=[], spwmap=[0, 0] )
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 6280
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::setapply(type, applypar)        .   Accor Jones: table=cal.B select= interp=linear spwmap=[0, 0] calWt=true
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        .   Accor Jones: table=cal.B select= interp=linear spwmap=[0, 0] calWt=true
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        For solint = inf, found 6 solution intervals.
17-Sep-2026 20:09:23 2026-09-18 00:09:24        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        Beginning solution interval 1/6 (~16% complete)
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        Beginning solution interval 2/6 (~33% complete)
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        Beginning solution interval 3/6 (~50% complete)
17-Sep-2026 20:09:23 2026-09-18 00:09:24        INFO        Calibrater::solve        Beginning solution interval 4/6 (~66% complete)
17-Sep-2026 20:09:24 2026-09-18 00:09:24        INFO        Calibrater::solve        Beginning solution interval 5/6 (~83% complete)
17-Sep-2026 20:09:24 2026-09-18 00:09:24        INFO        Calibrater::solve          Found good Accor Jones solutions in 6 solution intervals.
17-Sep-2026 20:09:24 2026-09-18 00:09:24        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        SPW: 0 [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        SPW: 1 [0, 0]  [3, 3]        [0, 0]            [0, 0]
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        [0, 0]  [6, 6]        [0, 0]            [0, 0]
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:23.553516 End time: 2026-09-18 00:09:25.105731
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:25.109839 End time: 2026-09-18 00:09:25.128937
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        rmtables( tablenames=['cal.B'] )
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        Removing cal.B
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:25.130021 End time: 2026-09-18 00:09:25.147019
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:25 .2026-09-18 00:09:25        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        accor::::casa        ##### Begin Task: accor              #####
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        accor::::casa        accor( vis='VLBA_copy.ms', caltable='cal.A', field='', spw='', intent='', selectdata=True, timerange='00:21:11.0~00:23:11.0', antenna='', scan='', observation='', msselect='', solint='inf', combine='', corrdepflags=False, append=False, docallib=False, callib='', gaintable=[], gainfield=[], interp=[], spwmap=[] )
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        calibrater::reset        Reseting solve/apply state
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::selectvis+         Selecting on time: '00:21:11.0~00:23:11.0'
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 1200
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::setsolve        Arranging to SOLVE:
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        calibrater::solve        Beginning solve-----------------------------
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        .   (None)
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        The following calibration term is arranged for solve:
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        For solint = inf, found 2 solution intervals.
17-Sep-2026 20:09:25 2026-09-18 00:09:25        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve        Beginning solution interval 1/2 (~50% complete)
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO        Calibrater::solve          Found good Accor Jones solutions in 2 solution intervals.
17-Sep-2026 20:09:25 2026-09-18 00:09:25        INFO                Writing solutions to table: cal.A
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]  [0, 0]
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve        ----- PER SPW INFO -----
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve        SPW: 0 [0, 0]  [1, 1]        [0, 0]            [0, 0]
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve        SPW: 1 [0, 0]  [1, 1]        [0, 0]            [0, 0]
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve        ----- GLOBAL INFO -----
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        Calibrater::solve        [0, 0]  [2, 2]        [0, 0]            [0, 0]
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        calibrater::solve        Finished solving.
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        accor::::casa        Task accor complete. Start time: 2026-09-18 00:09:25.150560 End time: 2026-09-18 00:09:26.337048
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        accor::::casa        ##### End Task: accor                #####
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        accor::::casa        ##########################################
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        Removing cal.A
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:26.341197 End time: 2026-09-18 00:09:26.362288
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:26 .2026-09-18 00:09:26        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        rmtables( tablenames=['cal.default'] )
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-18 00:09:26.437620 End time: 2026-09-18 00:09:26.438021
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        ##### End Task: rmtables             #####
17-Sep-2026 20:09:26 2026-09-18 00:09:26        INFO        rmtables::::casa        ##########################################
17-Sep-2026 20:09:26
17-Sep-2026 20:09:26 ----------------------------------------------------------------------
17-Sep-2026 20:09:26 Ran 18 tests in 62.745s
17-Sep-2026 20:09:26
17-Sep-2026 20:09:26 OK
17-Sep-2026 20:09:26
17-Sep-2026 20:09:26 Generating XML reports...
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        Using user configuration file /home/casatest/.casa/config.py
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        loaded config file : /wheeldirectory/casa-6.7.7-9-py3.12.el8/lib/py/lib/python3.12/site-packages/casaconfig/config.py
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        loaded config file : /home/casatest/.casa/config.py
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        config values
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            agg = False
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            cachedir = '/home/casatest/.casa'
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            casaconfig_verbose = 1
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            colors = 'Neutral'
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            data_auto_update = False
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            data_update_interval = 1
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            datapath = ['/opt/casa/data/master', '/opt/casa/data/casatestdata']
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            iplog = False
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            iplogfile = '/home/casatest/casa6/casatasks/tests/casashell_tests/work/test_task_appendantab_casashell/ipython-20260918-000927.log'
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            log2term = True
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            logfile = '/home/casatest/casa6/casatasks/tests/casashell_tests/work/test_task_appendantab_casashell/casa-20260918-000927.log'
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            measures_auto_update = False
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            measures_site = ['https://www.astron.nl/iers/', 'https://go.nrao.edu/iers/']
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            measures_site_interval = 2
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            measures_update_interval = 1
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            measurespath = '/opt/casa/data/master'
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            nogui = False
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            nologfile = False
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            nologger = True
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            pipeline = False
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            skipnetworkcheck = False
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            startupfile = '/home/casatest/.casa/startup.py'
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa            user_site = False
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        casarundata version is unknown, this is probably a legacy version not installed by casaconfig
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        measures version : WSRT_Measures_20211217-160001.ztar
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa        Checking Measures tables in data repository sub-directory /opt/casa/data/master/geodetic
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa          IERSeop2000 (version date, last date in table (UTC)): 2026/09/10/15:15, 2026/08/11/00:00:00
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa          IERSeop97 (version date, last date in table (UTC)): 2026/09/10/15:15, 2026/08/11/00:00:00
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa          IERSpredict (version date, last date in table (UTC)): 2026/09/17/15:15, 2026/12/16/00:00:00
17-Sep-2026 20:09:31 2026-09-18 00:09:32        INFO        ::casa          TAI_UTC (version date, last date in table (UTC)): 2026/09/03/15:15, 2017/01/01/00:00:00
17-Sep-2026 20:09:31
17-Sep-2026 20:09:31 Running tests...
17-Sep-2026 20:09:31 ----------------------------------------------------------------------
17-Sep-2026 20:09:31
17-Sep-2026 20:09:31 Using user configuration file /home/casatest/.casa/config.py
17-Sep-2026 20:09:31
17-Sep-2026 20:09:31 No event loop hook running.
17-Sep-2026 20:09:31 Using matplotlib backend: agg
17-Sep-2026 20:09:31 CASA 6.7.7.9 -- Common Astronomy Software Applications [6.7.7.9]
17-Sep-2026 20:11:11 2026-09-18 00:11:11        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:11:11 2026-09-18 00:11:11        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
17-Sep-2026 20:11:11 2026-09-18 00:11:11        INFO        appendantab::::casa        appendantab( vis='vla_copy.ms', outvis='corrected_overwrite.ms', antab='/opt/casa/data/casatestdata/unittest/appendantab/VLBA_7GHZ_GAINS.ANTAB', overwrite=True, append_tsys=False, append_gc=True )
17-Sep-2026 20:11:11 2026-09-18 00:11:12        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on vla_copy.ms...
17-Sep-2026 20:11:16 2026-09-18 00:11:17        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-18 00:11:11.402331 End time: 2026-09-18 00:11:16.718982
17-Sep-2026 20:11:16 2026-09-18 00:11:17        INFO        appendantab::::casa        ##### End Task: appendantab          #####
17-Sep-2026 20:11:16 2026-09-18 00:11:17        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:11:18 Antenna MK not present in the Measurement Set. MK values will be ignored.
17-Sep-2026 20:11:26 .2026-09-18 00:11:26        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:11:26 2026-09-18 00:11:26        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
17-Sep-2026 20:11:26 2026-09-18 00:11:26        INFO        appendantab::::casa        appendantab( vis='dataCopy.ms', outvis='appendedData.ms', antab='/opt/casa/data/casatestdata/unittest/appendantab/n14c3.antab', overwrite=False, append_tsys=True, append_gc=True )
17-Sep-2026 20:11:26 2026-09-18 00:11:26        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on dataCopy.ms...
17-Sep-2026 20:17:43 2026-09-18 00:17:44        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-18 00:11:26.359675 End time: 2026-09-18 00:17:43.821967
17-Sep-2026 20:17:43 2026-09-18 00:17:44        INFO        appendantab::::casa        ##### End Task: appendantab          #####
17-Sep-2026 20:17:43 2026-09-18 00:17:44        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:17:45 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:45 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:45 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:45 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:45 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:45 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:45 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:48 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:48 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:48 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
17-Sep-2026 20:17:48 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:48 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:48 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:17:48
17-Sep-2026 20:17:48 Testing column ANTENNA_ID
17-Sep-2026 20:17:48 Column ANTENNA_ID PASSED
17-Sep-2026 20:17:48
17-Sep-2026 20:17:48 Testing column FEED_ID
17-Sep-2026 20:17:48 Column FEED_ID PASSED
17-Sep-2026 20:17:48
17-Sep-2026 20:17:48 Testing column INTERVAL
17-Sep-2026 20:17:48 Column INTERVAL PASSED
17-Sep-2026 20:17:48
17-Sep-2026 20:17:48 Testing column SPECTRAL_WINDOW_ID
17-Sep-2026 20:17:48 Column SPECTRAL_WINDOW_ID PASSED
17-Sep-2026 20:17:48
17-Sep-2026 20:17:48 Testing column TIME
17-Sep-2026 20:17:48 Column TIME PASSED
17-Sep-2026 20:17:48
17-Sep-2026 20:17:48 Testing column TSYS
17-Sep-2026 20:17:48 Column TSYS PASSED
17-Sep-2026 20:17:48 -----------------------------
17-Sep-2026 20:17:58 .2026-09-18 00:17:58        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:17:58 2026-09-18 00:17:58        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
17-Sep-2026 20:17:58 2026-09-18 00:17:58        INFO        appendantab::::casa        appendantab( vis='dataCopy.ms', outvis='appendedData.ms', antab='/opt/casa/data/casatestdata/unittest/appendantab/n14c3.antab', overwrite=False, append_tsys=False, append_gc=True )
17-Sep-2026 20:17:58 2026-09-18 00:17:58        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on dataCopy.ms...
17-Sep-2026 20:23:27 2026-09-18 00:23:28        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-18 00:17:58.032006 End time: 2026-09-18 00:23:27.780448
17-Sep-2026 20:23:27 2026-09-18 00:23:28        INFO        appendantab::::casa        ##### End Task: appendantab          #####
17-Sep-2026 20:23:27 2026-09-18 00:23:28        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:23:30 -----------------------------
17-Sep-2026 20:23:30
17-Sep-2026 20:23:30 Testing column ANTENNA_ID
17-Sep-2026 20:23:30 Column ANTENNA_ID PASSED
17-Sep-2026 20:23:30
17-Sep-2026 20:23:30 Testing column FEED_ID
17-Sep-2026 20:23:30 Column FEED_ID PASSED
17-Sep-2026 20:23:30
17-Sep-2026 20:23:30 Testing column SPECTRAL_WINDOW_ID
17-Sep-2026 20:23:30 Column SPECTRAL_WINDOW_ID PASSED
17-Sep-2026 20:23:30
17-Sep-2026 20:23:30 Testing column TIME
17-Sep-2026 20:23:30 Column TIME PASSED
17-Sep-2026 20:23:30
17-Sep-2026 20:23:30 Testing column INTERVAL
17-Sep-2026 20:23:30 Column INTERVAL PASSED
17-Sep-2026 20:23:30
17-Sep-2026 20:23:30 Testing column TYPE
17-Sep-2026 20:23:30 Column TYPE PASSED
17-Sep-2026 20:23:30
17-Sep-2026 20:23:30 Testing column NUM_POLY
17-Sep-2026 20:23:30 Column NUM_POLY PASSED
17-Sep-2026 20:23:30
17-Sep-2026 20:23:30 Testing column SENSITIVITY
17-Sep-2026 20:23:30 Column SENSITIVITY PASSED
17-Sep-2026 20:23:37 .2026-09-18 00:23:38        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:23:37 2026-09-18 00:23:38        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
17-Sep-2026 20:23:37 2026-09-18 00:23:38        INFO        appendantab::::casa        appendantab( vis='dataCopy.ms', outvis='appendedData.ms', antab='/opt/casa/data/casatestdata/unittest/appendantab/n14c3.antab', overwrite=False, append_tsys=True, append_gc=False )
17-Sep-2026 20:23:37 2026-09-18 00:23:38        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on dataCopy.ms...
17-Sep-2026 20:28:52 2026-09-18 00:28:53        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-18 00:23:37.607904 End time: 2026-09-18 00:28:52.982802
17-Sep-2026 20:28:52 2026-09-18 00:28:53        INFO        appendantab::::casa        ##### End Task: appendantab          #####
17-Sep-2026 20:28:52 2026-09-18 00:28:53        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:28:54
17-Sep-2026 20:28:54 Testing column ANTENNA_ID
17-Sep-2026 20:28:54 Column ANTENNA_ID PASSED
17-Sep-2026 20:28:54
17-Sep-2026 20:28:54 Testing column FEED_ID
17-Sep-2026 20:28:54 Column FEED_ID PASSED
17-Sep-2026 20:28:54
17-Sep-2026 20:28:54 Testing column INTERVAL
17-Sep-2026 20:28:54 Column INTERVAL PASSED
17-Sep-2026 20:28:54
17-Sep-2026 20:28:54 Testing column SPECTRAL_WINDOW_ID
17-Sep-2026 20:28:54 Column SPECTRAL_WINDOW_ID PASSED
17-Sep-2026 20:28:54
17-Sep-2026 20:28:54 Testing column TIME
17-Sep-2026 20:28:54 Column TIME PASSED
17-Sep-2026 20:28:54
17-Sep-2026 20:28:54 Testing column TSYS
17-Sep-2026 20:28:54 Column TSYS PASSED
17-Sep-2026 20:29:00 .2026-09-18 00:29:01        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:29:00 2026-09-18 00:29:01        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
17-Sep-2026 20:29:00 2026-09-18 00:29:01        INFO        appendantab::::casa        appendantab( vis='dataCopy.ms', outvis='appendedData.ms', antab='/opt/casa/data/casatestdata/unittest/appendantab/n14c3.antab', overwrite=False, append_tsys=False, append_gc=True )
17-Sep-2026 20:29:00 2026-09-18 00:29:01        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on dataCopy.ms...
17-Sep-2026 20:34:36 2026-09-18 00:34:36        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-18 00:29:00.767815 End time: 2026-09-18 00:34:36.134989
17-Sep-2026 20:34:36 2026-09-18 00:34:36        INFO        appendantab::::casa        ##### End Task: appendantab          #####
17-Sep-2026 20:34:36 2026-09-18 00:34:36        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:34:36 2026-09-18 00:34:36        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:34:36 2026-09-18 00:34:36        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
17-Sep-2026 20:34:36 2026-09-18 00:34:36        INFO        appendantab::::casa        appendantab( vis='appendedData.ms', outvis='secondAppend.ms', antab='/opt/casa/data/casatestdata/unittest/appendantab/n14c3.antab', overwrite=False, append_tsys=False, append_gc=True )
17-Sep-2026 20:34:36 2026-09-18 00:34:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on appendedData.ms...
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
17-Sep-2026 20:34:43 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:43 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:44 2026-09-18 00:34:44        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-18 00:34:36.445207 End time: 2026-09-18 00:34:44.064799
17-Sep-2026 20:34:44 2026-09-18 00:34:44        INFO        appendantab::::casa        ##### End Task: appendantab          #####
17-Sep-2026 20:34:44 2026-09-18 00:34:44        INFO        appendantab::::casa        ##########################################
17-Sep-2026 20:34:46 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:34:46 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
17-Sep-2026 20:44:01 Agent 'cbt-el7-11.cv.nrao.edu' went offline while building CASASPECS-MASTERTESTCASALITHPY312-TTC2-294. The results of that build will not be available.