Build: #2 was successful

Job: Test Tasks Many Linux 2.28 Replace Casatasks import with casashell was successful

Build log

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

23-Sep-2026 12:32:15 2026-09-23 16:32:15        INFO                Writing solutions to table: cal.A
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
23-Sep-2026 12:32:16 2026-09-23 16:32: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]
23-Sep-2026 12:32:16 2026-09-23 16:32: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]
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        ----- PER SPW INFO -----
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        SPW: 0 [0, 0]  [3, 3]        [0, 0]            [0, 0]
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        SPW: 1 [0, 0]  [3, 3]        [0, 0]            [0, 0]
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        ----- GLOBAL INFO -----
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        [0, 0]  [6, 6]        [0, 0]            [0, 0]
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        calibrater::solve        Finished solving.
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        accor::::casa        Task accor complete. Start time: 2026-09-23 16:32:14.726489 End time: 2026-09-23 16:32:16.139490
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        accor::::casa        ##### End Task: accor                #####
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        rmtables::::casa        Removing cal.A
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:16.140933 End time: 2026-09-23 16:32:16.158902
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:16 .2026-09-23 16:32:16        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        accor::::casa        ##### Begin Task: accor              #####
23-Sep-2026 12:32:16 2026-09-23 16:32: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=[] )
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        calibrater::reset        Reseting solve/apply state
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::selectvis+         Selecting on scan: '2'
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 1200
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::setsolve        Arranging to SOLVE:
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        calibrater::solve        Beginning solve-----------------------------
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        .   (None)
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        The following calibration term is arranged for solve:
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        For solint = inf, found 2 solution intervals.
23-Sep-2026 12:32:16 2026-09-23 16:32:16        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve        Beginning solution interval 1/2 (~50% complete)
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO        Calibrater::solve          Found good Accor Jones solutions in 2 solution intervals.
23-Sep-2026 12:32:16 2026-09-23 16:32:16        INFO                Writing solutions to table: cal.A
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
23-Sep-2026 12:32:17 2026-09-23 16:32:17        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]
23-Sep-2026 12:32:17 2026-09-23 16:32:17        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]
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        ----- PER SPW INFO -----
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        SPW: 0 [0, 0]  [1, 1]        [0, 0]            [0, 0]
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        SPW: 1 [0, 0]  [1, 1]        [0, 0]            [0, 0]
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        ----- GLOBAL INFO -----
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        [0, 0]  [2, 2]        [0, 0]            [0, 0]
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        calibrater::solve        Finished solving.
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        accor::::casa        Task accor complete. Start time: 2026-09-23 16:32:16.162671 End time: 2026-09-23 16:32:17.163866
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        accor::::casa        ##### End Task: accor                #####
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        rmtables::::casa        Removing cal.A
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:17.168017 End time: 2026-09-23 16:32:17.187508
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:17 .2026-09-23 16:32:17        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        accor::::casa        ##### Begin Task: accor              #####
23-Sep-2026 12:32:17 2026-09-23 16:32:17        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=[] )
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        accor::calibrater::open        Opening MS: uid_copy.ms for calibration.
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        calibrater::reset        Reseting solve/apply state
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::selectvis        By selection 1080 rows are reduced to 540
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::setsolve        Arranging to SOLVE:
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        calibrater::solve        Beginning solve-----------------------------
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        .   (None)
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        The following calibration term is arranged for solve:
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 84368488281.1505
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 86213458984.275
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        For solint = inf, found 9 solution intervals.
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        Beginning solution interval 1/9 (~11% complete)
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        Beginning solution interval 2/9 (~22% complete)
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        Beginning solution interval 3/9 (~33% complete)
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        Beginning solution interval 4/9 (~44% complete)
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        Beginning solution interval 5/9 (~55% complete)
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        Beginning solution interval 6/9 (~66% complete)
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        Beginning solution interval 7/9 (~77% complete)
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve        Beginning solution interval 8/9 (~88% complete)
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO        Calibrater::solve          Found good Accor Jones solutions in 9 solution intervals.
23-Sep-2026 12:32:17 2026-09-23 16:32:17        INFO                Writing solutions to table: cal.A
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2   
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        ----- PER SPW INFO -----
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        SPW: 0 [0, 0]  [3, 3]        [0, 0]            [0, 0]
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        SPW: 1 [0, 0]  [6, 6]        [0, 0]            [0, 0]
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        ----- GLOBAL INFO -----
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        [0, 0]  [9, 9]        [0, 0]            [0, 0]
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        calibrater::solve        Finished solving.
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        accor::::casa        Task accor complete. Start time: 2026-09-23 16:32:17.191692 End time: 2026-09-23 16:32:19.282786
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        accor::::casa        ##### End Task: accor                #####
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        rmtables::::casa        Removing cal.A
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:19.286261 End time: 2026-09-23 16:32:19.303158
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        accor::::casa        ##### Begin Task: accor              #####
23-Sep-2026 12:32:19 2026-09-23 16:32: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=[] )
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        accor::calibrater::open        Opening MS: uid_copy.ms for calibration.
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        calibrater::reset        Reseting solve/apply state
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::selectvis+         Selecting on scan: '2'
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::selectvis        By selection 1080 rows are reduced to 180
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::setsolve        Arranging to SOLVE:
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        calibrater::solve        Beginning solve-----------------------------
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        .   (None)
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        The following calibration term is arranged for solve:
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 84368488281.1505
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 86213458984.275
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        For solint = inf, found 2 solution intervals.
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve        Beginning solution interval 1/2 (~50% complete)
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO        Calibrater::solve          Found good Accor Jones solutions in 2 solution intervals.
23-Sep-2026 12:32:19 2026-09-23 16:32:19        INFO                Writing solutions to table: cal.A
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2   
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        ----- PER SPW INFO -----
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        SPW: 0 [0, 0]  [1, 1]        [0, 0]            [0, 0]
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        SPW: 1 [0, 0]  [1, 1]        [0, 0]            [0, 0]
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        ----- GLOBAL INFO -----
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        [0, 0]  [2, 2]        [0, 0]            [0, 0]
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        calibrater::solve        Finished solving.
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        accor::::casa        Task accor complete. Start time: 2026-09-23 16:32:19.306127 End time: 2026-09-23 16:32:20.222034
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        accor::::casa        ##### End Task: accor                #####
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        Removing cal.A
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:20.225975 End time: 2026-09-23 16:32:20.245613
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:20.246792 End time: 2026-09-23 16:32:20.247342
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:20 .2026-09-23 16:32:20        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        accor::::casa        ##### Begin Task: accor              #####
23-Sep-2026 12:32:20 2026-09-23 16:32: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=[] )
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        calibrater::reset        Reseting solve/apply state
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 6280
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::setsolve        Arranging to SOLVE:
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=10s refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        calibrater::solve        Beginning solve-----------------------------
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        .   (None)
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        The following calibration term is arranged for solve:
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=10s refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        For solint = 10s, found 126 solution intervals.
23-Sep-2026 12:32:20 2026-09-23 16:32:20        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
23-Sep-2026 12:32:20 2026-09-23 16:32:20        INFO        Calibrater::solve        Beginning solution interval 13/126 (~10% complete)
23-Sep-2026 12:32:20 2026-09-23 16:32:21        INFO        Calibrater::solve        Beginning solution interval 26/126 (~20% complete)
23-Sep-2026 12:32:20 2026-09-23 16:32:21        INFO        Calibrater::solve        Beginning solution interval 39/126 (~30% complete)
23-Sep-2026 12:32:20 2026-09-23 16:32:21        INFO        Calibrater::solve        Beginning solution interval 52/126 (~41% complete)
23-Sep-2026 12:32:20 2026-09-23 16:32:21        INFO        Calibrater::solve        Beginning solution interval 65/126 (~51% complete)
23-Sep-2026 12:32:20 2026-09-23 16:32:21        INFO        Calibrater::solve        Beginning solution interval 78/126 (~61% complete)
23-Sep-2026 12:32:20 2026-09-23 16:32:21        INFO        Calibrater::solve        Beginning solution interval 91/126 (~72% complete)
23-Sep-2026 12:32:20 2026-09-23 16:32:21        INFO        Calibrater::solve        Beginning solution interval 104/126 (~82% complete)
23-Sep-2026 12:32:21 2026-09-23 16:32:21        INFO        Calibrater::solve        Beginning solution interval 117/126 (~92% complete)
23-Sep-2026 12:32:21 2026-09-23 16:32:21        INFO        Calibrater::solve          Found good Accor Jones solutions in 126 solution intervals.
23-Sep-2026 12:32:21 2026-09-23 16:32:21        INFO                Writing solutions to table: cal.A
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
23-Sep-2026 12:32:22 2026-09-23 16:32:22        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]
23-Sep-2026 12:32:22 2026-09-23 16:32:22        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]
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        ----- PER SPW INFO -----
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        SPW: 0 [0, 0]  [63, 63]        [0, 0]            [0, 0]
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        SPW: 1 [0, 0]  [63, 63]        [0, 0]            [0, 0]
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        ----- GLOBAL INFO -----
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        [0, 0]  [126, 126]        [0, 0]            [0, 0]
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        calibrater::solve        Finished solving.
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        accor::::casa        Task accor complete. Start time: 2026-09-23 16:32:20.251163 End time: 2026-09-23 16:32:22.012000
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        accor::::casa        ##### End Task: accor                #####
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        rmtables::::casa        Removing cal.A
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:22.017162 End time: 2026-09-23 16:32:22.035820
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:22 .2026-09-23 16:32:22        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        accor::::casa        ##### Begin Task: accor              #####
23-Sep-2026 12:32:22 2026-09-23 16:32:22        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=[] )
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        accor::calibrater::open        Opening MS: uid_copy.ms for calibration.
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        calibrater::reset        Reseting solve/apply state
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::selectvis+         Selecting on spw: '1'
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::selectvis        By selection 1080 rows are reduced to 405
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::selectvis        Frequency selection:
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::selectvis        .  Spw 1:0~0 (1 channels, step by 1)
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::setsolve        Arranging to SOLVE:
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        calibrater::solve        Beginning solve-----------------------------
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        .   (None)
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        The following calibration term is arranged for solve:
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 86213458984.275
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        For solint = inf, found 3 solution intervals.
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        Beginning solution interval 1/3 (~33% complete)
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve        Beginning solution interval 2/3 (~66% complete)
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO        Calibrater::solve          Found good Accor Jones solutions in 3 solution intervals.
23-Sep-2026 12:32:22 2026-09-23 16:32:22        INFO                Writing solutions to table: cal.A
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2   
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]  [0, 0]
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve        SPW: 1 [0, 0]  [0, 0]  [0, 0]
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve        ----- PER SPW INFO -----
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve        SPW: 0 [0, 0]  [0, 0]        [0, 0]            [0, 0]
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve        SPW: 1 [0, 0]  [3, 3]        [0, 0]            [0, 0]
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve        ----- GLOBAL INFO -----
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        Calibrater::solve        [0, 0]  [3, 3]        [0, 0]            [0, 0]
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        calibrater::solve        Finished solving.
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        accor::::casa        Task accor complete. Start time: 2026-09-23 16:32:22.039653 End time: 2026-09-23 16:32:22.993635
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        accor::::casa        ##### End Task: accor                #####
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
23-Sep-2026 12:32:22 2026-09-23 16:32:23        INFO        rmtables::::casa        Removing cal.A
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:22.997018 End time: 2026-09-23 16:32:23.014008
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:23 .2026-09-23 16:32:23        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        accor::::casa        ##### Begin Task: accor              #####
23-Sep-2026 12:32:23 2026-09-23 16:32:23        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=[] )
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        calibrater::reset        Reseting solve/apply state
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 6280
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::setsolve        Arranging to SOLVE:
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.B append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        calibrater::solve        Beginning solve-----------------------------
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        .   (None)
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        The following calibration term is arranged for solve:
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        .   Accor Jones: table=cal.B append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        For solint = inf, found 6 solution intervals.
23-Sep-2026 12:32:23 2026-09-23 16:32:23        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        Beginning solution interval 1/6 (~16% complete)
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        Beginning solution interval 2/6 (~33% complete)
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        Beginning solution interval 3/6 (~50% complete)
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        Beginning solution interval 4/6 (~66% complete)
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve        Beginning solution interval 5/6 (~83% complete)
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO        Calibrater::solve          Found good Accor Jones solutions in 6 solution intervals.
23-Sep-2026 12:32:23 2026-09-23 16:32:23        INFO                Writing solutions to table: cal.B
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
23-Sep-2026 12:32:24 2026-09-23 16:32: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]
23-Sep-2026 12:32:24 2026-09-23 16:32: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]
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        Calibrater::solve        ----- PER SPW INFO -----
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        Calibrater::solve        SPW: 0 [0, 0]  [3, 3]        [0, 0]            [0, 0]
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        Calibrater::solve        SPW: 1 [0, 0]  [3, 3]        [0, 0]            [0, 0]
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        Calibrater::solve        ----- GLOBAL INFO -----
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        Calibrater::solve        [0, 0]  [6, 6]        [0, 0]            [0, 0]
23-Sep-2026 12:32:24 2026-09-23 16:32:24        INFO        calibrater::solve        Finished solving.
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        accor::::casa        Task accor complete. Start time: 2026-09-23 16:32:23.017750 End time: 2026-09-23 16:32:24.502163
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        accor::::casa        ##### End Task: accor                #####
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        accor::::casa        ##### Begin Task: accor              #####
23-Sep-2026 12:32:24 2026-09-23 16:32:25        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] )
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        calibrater::reset        Reseting solve/apply state
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 6280
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::setapply(type, applypar)        .   Accor Jones: table=cal.B select= interp=linear spwmap=[0, 0] calWt=true
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::setsolve        Arranging to SOLVE:
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        calibrater::solve        Beginning solve-----------------------------
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        .   Accor Jones: table=cal.B select= interp=linear spwmap=[0, 0] calWt=true
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        The following calibration term is arranged for solve:
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        For solint = inf, found 6 solution intervals.
23-Sep-2026 12:32:24 2026-09-23 16:32:25        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        Beginning solution interval 1/6 (~16% complete)
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        Beginning solution interval 2/6 (~33% complete)
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        Beginning solution interval 3/6 (~50% complete)
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        Beginning solution interval 4/6 (~66% complete)
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve        Beginning solution interval 5/6 (~83% complete)
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO        Calibrater::solve          Found good Accor Jones solutions in 6 solution intervals.
23-Sep-2026 12:32:24 2026-09-23 16:32:25        INFO                Writing solutions to table: cal.A
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
23-Sep-2026 12:32:25 2026-09-23 16:32: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]
23-Sep-2026 12:32:25 2026-09-23 16:32: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]
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        ----- PER SPW INFO -----
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        SPW: 0 [0, 0]  [3, 3]        [0, 0]            [0, 0]
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        SPW: 1 [0, 0]  [3, 3]        [0, 0]            [0, 0]
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        ----- GLOBAL INFO -----
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        [0, 0]  [6, 6]        [0, 0]            [0, 0]
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        calibrater::solve        Finished solving.
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        accor::::casa        Task accor complete. Start time: 2026-09-23 16:32:24.506686 End time: 2026-09-23 16:32:25.792656
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        accor::::casa        ##### End Task: accor                #####
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        Removing cal.A
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:25.795933 End time: 2026-09-23 16:32:25.811470
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        rmtables( tablenames=['cal.B'] )
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        Removing cal.B
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:25.812383 End time: 2026-09-23 16:32:25.828014
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:25 .2026-09-23 16:32:26        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        accor::::casa        ##### Begin Task: accor              #####
23-Sep-2026 12:32:25 2026-09-23 16:32:26        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=[] )
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        accor::calibrater::open        ****Using NEW VI2-driven calibrater tool****
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        accor::calibrater::open        Opening MS: VLBA_copy.ms for calibration.
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        accor::Calibrater::        Initializing nominal selection to the whole MS.
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        accor::::casa        NB: accor automatically excludes crosso-correlations.
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        calibrater::reset        Reseting solve/apply state
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::selectvis+         Selecting on time: '00:21:11.0~00:23:11.0'
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::selectvis+         Selecting with TaQL: 'ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::selectvis        By selection 34540 rows are reduced to 1200
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect='ANTENNA1==ANTENNA2'
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::setsolve        Arranging to SOLVE:
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::setsolve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        calibrater::solve        Beginning solve-----------------------------
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        .   (None)
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        The following calibration term is arranged for solve:
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        .   Accor Jones: table=cal.A append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        ChannelAverageTVI::parseConfiguration        Channel bin is [-1, -1]
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        FreqMetaData::calcFreqMeta        Derived frequency meta-info for solutions:
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        FreqMetaData::calcFreqMeta         Selected spw=0 (nchan=1) has centroid freq = 43103500000
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        FreqMetaData::calcFreqMeta         Selected spw=1 (nchan=1) has centroid freq = 43231500000
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        For solint = inf, found 2 solution intervals.
23-Sep-2026 12:32:25 2026-09-23 16:32:26        WARN        MSIter::setFeedInfo         time dependent feed table encountered - not correctly handled  - continuing anyway
23-Sep-2026 12:32:25 2026-09-23 16:32:26        INFO        Calibrater::solve        Beginning solution interval 1/2 (~50% complete)
23-Sep-2026 12:32:26 2026-09-23 16:32:26        INFO        Calibrater::solve          Found good Accor Jones solutions in 2 solution intervals.
23-Sep-2026 12:32:26 2026-09-23 16:32:26        INFO                Writing solutions to table: cal.A
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        Calibrater::solve        ----- PER ANTENNA INFO -----
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        Calibrater::solve               ANT: 0    ANT: 1    ANT: 2    ANT: 3    ANT: 4    ANT: 5    ANT: 6    ANT: 7    ANT: 8    ANT: 9   
23-Sep-2026 12:32:26 2026-09-23 16:32:27        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]
23-Sep-2026 12:32:26 2026-09-23 16:32:27        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]
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        Calibrater::solve        ----- PER SPW INFO -----
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        Calibrater::solve               expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        Calibrater::solve        SPW: 0 [0, 0]  [1, 1]        [0, 0]            [0, 0]
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        Calibrater::solve        SPW: 1 [0, 0]  [1, 1]        [0, 0]            [0, 0]
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        Calibrater::solve        ----- GLOBAL INFO -----
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        Calibrater::solve        expected  data_unflagged  above_minblperant  above_minsnr
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        Calibrater::solve        [0, 0]  [2, 2]        [0, 0]            [0, 0]
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        calibrater::solve        Finished solving.
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        accor::::casa        Task accor complete. Start time: 2026-09-23 16:32:25.830967 End time: 2026-09-23 16:32:26.748302
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        accor::::casa        ##### End Task: accor                #####
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        accor::::casa        ##########################################
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        rmtables( tablenames=['cal.A'] )
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        Removing cal.A
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:26.752166 End time: 2026-09-23 16:32:26.770596
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:26 .2026-09-23 16:32:27        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        ##### Begin Task: rmtables           #####
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        rmtables( tablenames=['cal.default'] )
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        Task rmtables complete. Start time: 2026-09-23 16:32:26.839025 End time: 2026-09-23 16:32:26.839816
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        ##### End Task: rmtables             #####
23-Sep-2026 12:32:26 2026-09-23 16:32:27        INFO        rmtables::::casa        ##########################################
23-Sep-2026 12:32:26
23-Sep-2026 12:32:26 ----------------------------------------------------------------------
23-Sep-2026 12:32:26 Ran 18 tests in 52.675s
23-Sep-2026 12:32:26
23-Sep-2026 12:32:26 OK
23-Sep-2026 12:32:26
23-Sep-2026 12:32:26 Generating XML reports...
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        Using user configuration file /home/casatest/.casa/config.py
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        loaded config file : /wheeldirectory/casa-CAS-14882-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casaconfig/config.py
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        loaded config file : /home/casatest/.casa/config.py
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        config values
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            agg = False
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            cachedir = '/home/casatest/.casa'
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            casaconfig_verbose = 1
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            colors = 'Neutral'
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            data_auto_update = False
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            data_update_interval = 1
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            datapath = ['/opt/casa/data/master', '/opt/casa/data/casatestdata']
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            iplog = False
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            iplogfile = '/home/casatest/casa6/casatasks/tests/casashell_tests/work/test_task_appendantab_casashell/ipython-20260923-163228.log'
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            log2term = True
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            logfile = '/home/casatest/casa6/casatasks/tests/casashell_tests/work/test_task_appendantab_casashell/casa-20260923-163228.log'
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            measures_auto_update = False
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            measures_site = ['https://www.astron.nl/iers/', 'https://go.nrao.edu/iers/']
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            measures_site_interval = 2
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            measures_update_interval = 1
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            measurespath = '/opt/casa/data/master'
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            nogui = False
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            nologfile = False
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            nologger = True
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            pipeline = False
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            skipnetworkcheck = False
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            startupfile = '/home/casatest/.casa/startup.py'
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa            user_site = False
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        casarundata version is unknown, this is probably a legacy version not installed by casaconfig
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        measures version : WSRT_Measures_20211217-160001.ztar
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa        Checking Measures tables in data repository sub-directory /opt/casa/data/master/geodetic
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa          IERSeop2000 (version date, last date in table (UTC)): 2026/09/10/15:15, 2026/08/11/00:00:00
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa          IERSeop97 (version date, last date in table (UTC)): 2026/09/10/15:15, 2026/08/11/00:00:00
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa          IERSpredict (version date, last date in table (UTC)): 2026/09/22/15:15, 2026/12/21/00:00:00
23-Sep-2026 12:32:31 2026-09-23 16:32:32        INFO        ::casa          TAI_UTC (version date, last date in table (UTC)): 2026/09/03/15:15, 2017/01/01/00:00:00
23-Sep-2026 12:32:31
23-Sep-2026 12:32:31 Running tests...
23-Sep-2026 12:32:31 ----------------------------------------------------------------------
23-Sep-2026 12:32:31
23-Sep-2026 12:32:31 Using user configuration file /home/casatest/.casa/config.py
23-Sep-2026 12:32:31
23-Sep-2026 12:32:31 No event loop hook running.
23-Sep-2026 12:32:31 Using matplotlib backend: agg
23-Sep-2026 12:32:31 CASA 6.7.7.7a14882.dev1 -- Common Astronomy Software Applications [6.7.7.7a14882.dev1]
23-Sep-2026 12:35:06 2026-09-23 16:35:07        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:35:06 2026-09-23 16:35:07        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
23-Sep-2026 12:35:06 2026-09-23 16:35:07        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 )
23-Sep-2026 12:35:06 2026-09-23 16:35:07        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on vla_copy.ms...
23-Sep-2026 12:35:12 2026-09-23 16:35:13        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-23 16:35:06.898507 End time: 2026-09-23 16:35:12.995762
23-Sep-2026 12:35:12 2026-09-23 16:35:13        INFO        appendantab::::casa        ##### End Task: appendantab          #####
23-Sep-2026 12:35:12 2026-09-23 16:35:13        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:35:13 Antenna MK not present in the Measurement Set. MK values will be ignored.
23-Sep-2026 12:35:18 .2026-09-23 16:35:19        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:35:18 2026-09-23 16:35:19        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
23-Sep-2026 12:35:18 2026-09-23 16:35:19        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 )
23-Sep-2026 12:35:18 2026-09-23 16:35:19        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on dataCopy.ms...
23-Sep-2026 12:37:12 2026-09-23 16:37:12        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-23 16:35:18.504568 End time: 2026-09-23 16:37:12.245722
23-Sep-2026 12:37:12 2026-09-23 16:37:12        INFO        appendantab::::casa        ##### End Task: appendantab          #####
23-Sep-2026 12:37:12 2026-09-23 16:37:12        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:37:13
23-Sep-2026 12:37:13 Testing column ANTENNA_ID
23-Sep-2026 12:37:13 Column ANTENNA_ID PASSED
23-Sep-2026 12:37:13
23-Sep-2026 12:37:13 Testing column FEED_ID
23-Sep-2026 12:37:13 Column FEED_ID PASSED
23-Sep-2026 12:37:13
23-Sep-2026 12:37:13 Testing column INTERVAL
23-Sep-2026 12:37:13 Column INTERVAL PASSED
23-Sep-2026 12:37:13
23-Sep-2026 12:37:13 Testing column SPECTRAL_WINDOW_ID
23-Sep-2026 12:37:13 Column SPECTRAL_WINDOW_ID PASSED
23-Sep-2026 12:37:13
23-Sep-2026 12:37:13 Testing column TIME
23-Sep-2026 12:37:13 Column TIME PASSED
23-Sep-2026 12:37:13
23-Sep-2026 12:37:13 Testing column TSYS
23-Sep-2026 12:37:13 Column TSYS PASSED
23-Sep-2026 12:37:13 -----------------------------
23-Sep-2026 12:37:18 .2026-09-23 16:37:19        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:37:18 2026-09-23 16:37:19        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
23-Sep-2026 12:37:18 2026-09-23 16:37:19        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 )
23-Sep-2026 12:37:18 2026-09-23 16:37:19        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on dataCopy.ms...
23-Sep-2026 12:42:34 2026-09-23 16:42:35        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-23 16:37:18.534023 End time: 2026-09-23 16:42:34.723626
23-Sep-2026 12:42:34 2026-09-23 16:42:35        INFO        appendantab::::casa        ##### End Task: appendantab          #####
23-Sep-2026 12:42:34 2026-09-23 16:42:35        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:42:35 -----------------------------
23-Sep-2026 12:42:35
23-Sep-2026 12:42:35 Testing column ANTENNA_ID
23-Sep-2026 12:42:35 Column ANTENNA_ID PASSED
23-Sep-2026 12:42:35
23-Sep-2026 12:42:35 Testing column FEED_ID
23-Sep-2026 12:42:35 Column FEED_ID PASSED
23-Sep-2026 12:42:35
23-Sep-2026 12:42:35 Testing column SPECTRAL_WINDOW_ID
23-Sep-2026 12:42:35 Column SPECTRAL_WINDOW_ID PASSED
23-Sep-2026 12:42:35
23-Sep-2026 12:42:35 Testing column TIME
23-Sep-2026 12:42:35 Column TIME PASSED
23-Sep-2026 12:42:35
23-Sep-2026 12:42:35 Testing column INTERVAL
23-Sep-2026 12:42:35 Column INTERVAL PASSED
23-Sep-2026 12:42:35
23-Sep-2026 12:42:35 Testing column TYPE
23-Sep-2026 12:42:35 Column TYPE PASSED
23-Sep-2026 12:42:35
23-Sep-2026 12:42:35 Testing column NUM_POLY
23-Sep-2026 12:42:35 Column NUM_POLY PASSED
23-Sep-2026 12:42:35
23-Sep-2026 12:42:35 Testing column SENSITIVITY
23-Sep-2026 12:42:35 Column SENSITIVITY PASSED
23-Sep-2026 12:42:41 .2026-09-23 16:42:41        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:42:41 2026-09-23 16:42:41        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
23-Sep-2026 12:42:41 2026-09-23 16:42:41        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 )
23-Sep-2026 12:42:41 2026-09-23 16:42:41        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on dataCopy.ms...
23-Sep-2026 12:43:39 2026-09-23 16:43:39        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-23 16:42:41.075847 End time: 2026-09-23 16:43:39.450155
23-Sep-2026 12:43:39 2026-09-23 16:43:39        INFO        appendantab::::casa        ##### End Task: appendantab          #####
23-Sep-2026 12:43:39 2026-09-23 16:43:39        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:45:52
23-Sep-2026 12:45:52 Testing column ANTENNA_ID
23-Sep-2026 12:45:52 Column ANTENNA_ID PASSED
23-Sep-2026 12:45:52
23-Sep-2026 12:45:52 Testing column FEED_ID
23-Sep-2026 12:45:52 Column FEED_ID PASSED
23-Sep-2026 12:45:52
23-Sep-2026 12:45:52 Testing column INTERVAL
23-Sep-2026 12:45:52 Column INTERVAL PASSED
23-Sep-2026 12:45:52
23-Sep-2026 12:45:52 Testing column SPECTRAL_WINDOW_ID
23-Sep-2026 12:45:52 Column SPECTRAL_WINDOW_ID PASSED
23-Sep-2026 12:45:52
23-Sep-2026 12:45:52 Testing column TIME
23-Sep-2026 12:45:52 Column TIME PASSED
23-Sep-2026 12:45:52
23-Sep-2026 12:45:52 Testing column TSYS
23-Sep-2026 12:45:52 Column TSYS PASSED
23-Sep-2026 12:45:56 .2026-09-23 16:45:57        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:45:56 2026-09-23 16:45:57        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
23-Sep-2026 12:45:56 2026-09-23 16:45:57        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 )
23-Sep-2026 12:45:56 2026-09-23 16:45:57        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on dataCopy.ms...
23-Sep-2026 12:46:55 2026-09-23 16:46:55        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-23 16:45:56.700670 End time: 2026-09-23 16:46:55.480279
23-Sep-2026 12:46:55 2026-09-23 16:46:55        INFO        appendantab::::casa        ##### End Task: appendantab          #####
23-Sep-2026 12:46:55 2026-09-23 16:46:55        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:46:55 2026-09-23 16:46:56        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:46:55 2026-09-23 16:46:56        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
23-Sep-2026 12:46:55 2026-09-23 16:46:56        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 )
23-Sep-2026 12:46:55 2026-09-23 16:46:56        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on appendedData.ms...
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:47:04 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 8 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 0 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 10 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 2 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 4 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 3 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 9 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:47:04 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:49:33 2026-09-23 16:49:34        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-23 16:46:55.686049 End time: 2026-09-23 16:49:33.855276
23-Sep-2026 12:49:33 2026-09-23 16:49:34        INFO        appendantab::::casa        ##### End Task: appendantab          #####
23-Sep-2026 12:49:33 2026-09-23 16:49:34        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:52:26 antenna: 6 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 5 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 1 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:26 antenna: 11 has data present for the time 4920700008.000002. The antab data for this value will be ignored
23-Sep-2026 12:52:34 .2026-09-23 16:52:34        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:52:34 2026-09-23 16:52:34        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
23-Sep-2026 12:52:34 2026-09-23 16:52:34        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 )
23-Sep-2026 12:52:34 2026-09-23 16:52:34        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on dataCopy.ms...
23-Sep-2026 12:56:55 2026-09-23 16:56:56        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-23 16:52:34.286200 End time: 2026-09-23 16:56:55.691877
23-Sep-2026 12:56:55 2026-09-23 16:56:56        INFO        appendantab::::casa        ##### End Task: appendantab          #####
23-Sep-2026 12:56:55 2026-09-23 16:56:56        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:56:55 2026-09-23 16:56:56        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:56:55 2026-09-23 16:56:56        INFO        appendantab::::casa        ##### Begin Task: appendantab        #####
23-Sep-2026 12:56:55 2026-09-23 16:56:56        INFO        appendantab::::casa        appendantab( vis='appendedData.ms', outvis='secondAppend.ms', antab='/opt/casa/data/casatestdata/unittest/appendantab/n14c3.antab', overwrite=False, append_tsys=True, append_gc=False )
23-Sep-2026 12:56:55 2026-09-23 16:56:56        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on appendedData.ms...
23-Sep-2026 12:57:01 2026-09-23 16:57:01        INFO        appendantab::::casa        Task appendantab complete. Start time: 2026-09-23 16:56:55.981424 End time: 2026-09-23 16:57:01.417457
23-Sep-2026 12:57:01 2026-09-23 16:57:01        INFO        appendantab::::casa        ##### End Task: appendantab          #####
23-Sep-2026 12:57:01 2026-09-23 16:57:01        INFO        appendantab::::casa        ##########################################
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO SPW ID -1 and freq to [0, 0]
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 ANTAB HAS NO FREQ INFORMATION DEFAULTING TO FREQ ID -1
23-Sep-2026 12:57:03 .
23-Sep-2026 12:57:03 ----------------------------------------------------------------------
23-Sep-2026 12:57:03 Ran 6 tests in 1472.241s
23-Sep-2026 12:57:03
23-Sep-2026 12:57:03 OK
23-Sep-2026 12:57:03
23-Sep-2026 12:57:03 Generating XML reports...
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        Using user configuration file /home/casatest/.casa/config.py
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        loaded config file : /wheeldirectory/casa-CAS-14882-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casaconfig/config.py
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        loaded config file : /home/casatest/.casa/config.py
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        config values
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            agg = False
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            cachedir = '/home/casatest/.casa'
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            casaconfig_verbose = 1
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            colors = 'Neutral'
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            data_auto_update = False
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            data_update_interval = 1
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            datapath = ['/opt/casa/data/master', '/opt/casa/data/casatestdata']
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            iplog = False
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            iplogfile = '/home/casatest/casa6/casatasks/tests/casashell_tests/work/test_task_applycal_casashell/ipython-20260923-165705.log'
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            log2term = True
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            logfile = '/home/casatest/casa6/casatasks/tests/casashell_tests/work/test_task_applycal_casashell/casa-20260923-165705.log'
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            measures_auto_update = False
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            measures_site = ['https://www.astron.nl/iers/', 'https://go.nrao.edu/iers/']
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            measures_site_interval = 2
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            measures_update_interval = 1
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            measurespath = '/opt/casa/data/master'
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            nogui = False
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            nologfile = False
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            nologger = True
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            pipeline = False
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            skipnetworkcheck = False
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            startupfile = '/home/casatest/.casa/startup.py'
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa            user_site = False
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        casarundata version is unknown, this is probably a legacy version not installed by casaconfig
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        measures version : WSRT_Measures_20211217-160001.ztar
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa        Checking Measures tables in data repository sub-directory /opt/casa/data/master/geodetic
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa          IERSeop2000 (version date, last date in table (UTC)): 2026/09/10/15:15, 2026/08/11/00:00:00
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa          IERSeop97 (version date, last date in table (UTC)): 2026/09/10/15:15, 2026/08/11/00:00:00
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa          IERSpredict (version date, last date in table (UTC)): 2026/09/22/15:15, 2026/12/21/00:00:00
23-Sep-2026 12:57:18 2026-09-23 16:57:18        INFO        ::casa          TAI_UTC (version date, last date in table (UTC)): 2026/09/03/15:15, 2017/01/01/00:00:00
23-Sep-2026 12:57:18 Traceback (most recent call last):
23-Sep-2026 12:57:18   File "/wheeldirectory/casa-CAS-14882-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casashell/private/init_system.py", line 280, in __evprop__
23-Sep-2026 12:57:18     run_path( args[0], init_globals=exec_globals, run_name='__main__' )
23-Sep-2026 12:57:18   File "<frozen runpy>", line 286, in run_path
23-Sep-2026 12:57:18   File "<frozen runpy>", line 98, in _run_module_code
23-Sep-2026 12:57:18   File "<frozen runpy>", line 88, in _run_code
23-Sep-2026 12:57:18   File "/home/casatest/casa6/casatasks/tests/casashell_tests/test_task_applycal_casashell.py", line 32, in <module>
23-Sep-2026 12:57:18     from casashell import applycal, mstransform, gaincal, casalog, clearcal, bandpass, defintent
23-Sep-2026 12:57:18 ImportError: cannot import name 'casalog' from 'casashell' (/wheeldirectory/casa-CAS-14882-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casashell/__init__.py)
23-Sep-2026 12:57:18
23-Sep-2026 12:57:18 Using user configuration file /home/casatest/.casa/config.py
23-Sep-2026 12:57:18
23-Sep-2026 12:57:18 No event loop hook running.
23-Sep-2026 12:57:18 Using matplotlib backend: agg
23-Sep-2026 12:57:18 CASA 6.7.7.7a14882.dev1 -- Common Astronomy Software Applications [6.7.7.7a14882.dev1]
23-Sep-2026 12:57:18 CASA exits with a non-zero status : exception: <class 'ImportError'>
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        Using user configuration file /home/casatest/.casa/config.py
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        loaded config file : /wheeldirectory/casa-CAS-14882-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casaconfig/config.py
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        loaded config file : /home/casatest/.casa/config.py
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        config values
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            agg = False
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            cachedir = '/home/casatest/.casa'
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            casaconfig_verbose = 1
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            colors = 'Neutral'
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            data_auto_update = False
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            data_update_interval = 1
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            datapath = ['/opt/casa/data/master', '/opt/casa/data/casatestdata']
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            iplog = False
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            iplogfile = '/home/casatest/casa6/casatasks/tests/casashell_tests/work/test_task_asdmsummary_casashell/ipython-20260923-165719.log'
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            log2term = True
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            logfile = '/home/casatest/casa6/casatasks/tests/casashell_tests/work/test_task_asdmsummary_casashell/casa-20260923-165719.log'
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            measures_auto_update = False
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            measures_site = ['https://www.astron.nl/iers/', 'https://go.nrao.edu/iers/']
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            measures_site_interval = 2
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            measures_update_interval = 1
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            measurespath = '/opt/casa/data/master'
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            nogui = False
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            nologfile = False
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            nologger = True
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            pipeline = False
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            skipnetworkcheck = False
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            startupfile = '/home/casatest/.casa/startup.py'
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa            user_site = False
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        casarundata version is unknown, this is probably a legacy version not installed by casaconfig
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        measures version : WSRT_Measures_20211217-160001.ztar
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa        Checking Measures tables in data repository sub-directory /opt/casa/data/master/geodetic
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa          IERSeop2000 (version date, last date in table (UTC)): 2026/09/10/15:15, 2026/08/11/00:00:00
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa          IERSeop97 (version date, last date in table (UTC)): 2026/09/10/15:15, 2026/08/11/00:00:00
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa          IERSpredict (version date, last date in table (UTC)): 2026/09/22/15:15, 2026/12/21/00:00:00
23-Sep-2026 12:57:22 2026-09-23 16:57:23        INFO        ::casa          TAI_UTC (version date, last date in table (UTC)): 2026/09/03/15:15, 2017/01/01/00:00:00
23-Sep-2026 12:57:22 Traceback (most recent call last):
23-Sep-2026 12:57:22   File "/wheeldirectory/casa-CAS-14882-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casashell/private/init_system.py", line 280, in __evprop__
23-Sep-2026 12:57:22     run_path( args[0], init_globals=exec_globals, run_name='__main__' )
23-Sep-2026 12:57:22   File "<frozen runpy>", line 286, in run_path
23-Sep-2026 12:57:22   File "<frozen runpy>", line 98, in _run_module_code
23-Sep-2026 12:57:22   File "<frozen runpy>", line 88, in _run_code
23-Sep-2026 12:57:22   File "/home/casatest/casa6/casatasks/tests/casashell_tests/test_task_asdmsummary_casashell.py", line 27, in <module>
23-Sep-2026 12:57:22     from casashell import asdmsummary, casalog
23-Sep-2026 12:57:22 ImportError: cannot import name 'casalog' from 'casashell' (/wheeldirectory/casa-CAS-14882-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casashell/__init__.py)
23-Sep-2026 12:57:22
23-Sep-2026 12:57:22 Using user configuration file /home/casatest/.casa/config.py
23-Sep-2026 12:57:22
23-Sep-2026 12:57:22 No event loop hook running.
23-Sep-2026 12:57:22 Using matplotlib backend: agg
23-Sep-2026 12:57:22 CASA 6.7.7.7a14882.dev1 -- Common Astronomy Software Applications [6.7.7.7a14882.dev1]
23-Sep-2026 12:57:23 CASA exits with a non-zero status : exception: <class 'ImportError'>
23-Sep-2026 12:57:25 total 779068
23-Sep-2026 12:57:25 drwxr-xr-x 5 casaci casaci      4096 Sep 14 02:07 casa-CAS-14882-1-py3.12.el8
23-Sep-2026 12:57:25 -rw-r--r-- 1 casaci  15001 797748744 Sep 23 16:24 casa-CAS-14882-1-py3.12.el8.tar.xz
23-Sep-2026 12:57:25 drwxr-xr-x 2 casaci root        4096 Sep 23 16:57 logs
23-Sep-2026 12:57:25 drwxr-xr-x 2 casaci root        4096 Sep 23 16:57 xml
23-Sep-2026 12:57:25 total 192
23-Sep-2026 12:57:25 -rw-r--r-- 1 root root 159602 Sep 23 16:57 test_task_accor_casashell_out.log
23-Sep-2026 12:57:25 -rw-r--r-- 1 root root  25706 Sep 23 16:57 test_task_appendantab_casashell_out.log
23-Sep-2026 12:57:25 -rw-r--r-- 1 root root   4036 Sep 23 16:57 test_task_applycal_casashell_out.log
23-Sep-2026 12:57:25 -rw-r--r-- 1 root root   3995 Sep 23 16:57 test_task_asdmsummary_casashell_out.log
23-Sep-2026 12:57:25 total 28
23-Sep-2026 12:57:25 -rw-r--r-- 1 root root  6361 Sep 23 16:57 test_task_accor_casashell.py.xml
23-Sep-2026 12:57:25 -rw-r--r-- 1 root root 17815 Sep 23 16:57 test_task_appendantab_casashell.py.xml
23-Sep-2026 12:57:25 Finished task 'Test wheel as casatest' with result: Success
23-Sep-2026 12:57:25 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
23-Sep-2026 12:57:25 Parsing test results under /export/home/cbt-el7-11/bamboohome/xml-data/build-dir/CASA-REDTFX30-TTML2RCWC...
23-Sep-2026 12:57:25 Finished task 'Parse tests' with result: Success
23-Sep-2026 12:57:25 Starting task 'Script' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
23-Sep-2026 12:57:25
Beginning to execute external process for build 'CASA - Release Engineering Dev Test Full XML 3.12 - CAS-14882 - Test Tasks Many Linux 2.28 Replace Casatasks with casashell #2 (CASA-REDTFX30-TTML2RCWC-2)'
... running command line:
/export/home/cbt-el7-11/bamboohome/temp/CASA-REDTFX30-TTML2RCWC-2-ScriptBuildTask-14861107404090851037.sh
... in: /export/home/cbt-el7-11/bamboohome/xml-data/build-dir/CASA-REDTFX30-TTML2RCWC
23-Sep-2026 12:57:25 Starting cleanup
23-Sep-2026 12:57:38 wheel-container-test
23-Sep-2026 12:57:39 wheel-container-test
23-Sep-2026 12:57:39 Cleanup complete
23-Sep-2026 12:57:39 Finished task 'Script' with result: Success
23-Sep-2026 12:57:39 Running post build plugin 'Docker Container Cleanup'
23-Sep-2026 12:57:39
Beginning to execute external process for build 'CASA - Release Engineering Dev Test Full XML 3.12 - CAS-14882 - Test Tasks Many Linux 2.28 Replace Casatasks with casashell #2 (CASA-REDTFX30-TTML2RCWC-2)'
... running command line:
/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-11/bamboohome/xml-data/build-dir/CASA-REDTFX30-TTML2RCWC
23-Sep-2026 12:57:39 Error response from daemon: No such container: wheel-container-test
23-Sep-2026 12:57:39 Running post build plugin 'NCover Results Collector'
23-Sep-2026 12:57:39 Running post build plugin 'Build Results Label Collector'
23-Sep-2026 12:57:39 Running post build plugin 'Clover Results Collector'
23-Sep-2026 12:57:39 Running post build plugin 'npm Cache Cleanup'
23-Sep-2026 12:57:39 Running post build plugin 'Artifact Copier'
23-Sep-2026 12:57:39 Publishing an artifact: Log Files
23-Sep-2026 12:57:39 Finished publishing of artifact Non required job artifact Http Compression On : [Log Files], patterns: [*.log] anchored at: [wheeldirectory/logs/] in 152.2 ms
23-Sep-2026 12:57:39 Publishing an artifact: XML Files
23-Sep-2026 12:57:39 Finished publishing of artifact Non required job artifact Http Compression On : [XML Files], patterns: [*.xml] anchored at: [wheeldirectory/xml/] in 40.44 ms
23-Sep-2026 12:57:39 Finalising the build...
23-Sep-2026 12:57:39 Stopping timer.
23-Sep-2026 12:57:39 Build CASA-REDTFX30-TTML2RCWC-2 completed.
23-Sep-2026 12:57:40 Running on server: post build plugin 'Build Hanging Detection Configuration'
23-Sep-2026 12:57:40 Running on server: post build plugin 'NCover Results Collector'
23-Sep-2026 12:57:40 Running on server: post build plugin 'Build Labeller'
23-Sep-2026 12:57:40 Running on server: post build plugin 'Clover Delta Calculator'
23-Sep-2026 12:57:40 Running on server: post build plugin 'Maven Dependencies Postprocessor'
23-Sep-2026 12:57:40 All post build plugins have finished
23-Sep-2026 12:57:40 Generating build results summary...
23-Sep-2026 12:57:40 Saving build results to disk...
23-Sep-2026 12:57:40 Store variable context...
23-Sep-2026 12:57:40 Finished building CASA-REDTFX30-TTML2RCWC-2.