2025-03-18 21:51:03        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1445+09900002_0, spw= 0, nchan= 63
2025-03-18 21:51:03        INFO        GJonesSpline::solve()         Accumulating data for:  field= N5921_2, spw= 0, nchan= 63
2025-03-18 21:51:03        INFO        GJonesSpline::solve()         Number of timestamps in data = 60
2025-03-18 21:51:03        INFO        GJonesSpline::getKnots()         Gridded splinetime = 5280 sec.
2025-03-18 21:51:03        INFO        GJonesSpline::solve()         Number of cubic spline control points = 4
2025-03-18 21:51:03        INFO        GJonesSpline::solve()         Number of cubic spline knots = 8
2025-03-18 21:51:03        INFO        GJonesSpline::solve()         Number of cubic spline segments = 1
2025-03-18 21:51:03        INFO        GJonesSpline::solve()         Fitting amplitude spline.
2025-03-18 21:51:03        INFO        GJonesSpline::solve()         Searching for and correcting phase-wraps on each baseline.
2025-03-18 21:51:03        INFO        GJonesSpline::solve()         Fitting phase spline.
2025-03-18 21:51:03        INFO        GJonesSpline::updateCalTable         Storing solutions in table gainspline
2025-03-18 21:51:03        INFO        calibrater::solve        Finished solving.
2025-03-18 21:51:03        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
2025-03-18 21:51:03        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
2025-03-18 21:51:03        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
2025-03-18 21:51:03        INFO                Clearing all model records in MS header.
2025-03-18 21:51:03        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-03-18 21:51:04        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
2025-03-18 21:51:04        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
2025-03-18 21:51:04        INFO        Calibrater::        Initializing nominal selection to the whole MS.
2025-03-18 21:51:04        INFO        calibrater::specifycal        Beginning specifycal-----------------------
2025-03-18 21:51:04        INFO                Creating G Jones table from specified parameters.
2025-03-18 21:51:04        INFO                Generating 'G' corrections.
2025-03-18 21:51:04        INFO                Specified parameter(s) (per spw and pol) repeated on all antennas.
2025-03-18 21:51:04        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
2025-03-18 21:51:04        INFO                spwId=1 antId=<all> polId=<all> parameter= 3   (ip0,ip1 = [0, 0, 0],[1, 0, 9])
2025-03-18 21:51:04        INFO                Writing solutions to table: testcalout.cal
2025-03-18 21:51:04        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
2025-03-18 21:51:05        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
2025-03-18 21:51:05        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
2025-03-18 21:51:05        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
2025-03-18 21:51:05        INFO                Clearing all model records in MS header.
2025-03-18 21:51:05        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-03-18 21:51:05        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
2025-03-18 21:51:05        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
2025-03-18 21:51:05        INFO        Calibrater::        Initializing nominal selection to the whole MS.
2025-03-18 21:51:05        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
2025-03-18 21:51:05        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
2025-03-18 21:51:06        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-03-18 21:51:06        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
2025-03-18 21:51:06        INFO        Calibrater::setsolve        Arranging to SOLVE:
2025-03-18 21:51:06        INFO        Calibrater::setsolve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-03-18 21:51:06        INFO        Calibrater::state        The following calibration terms are arranged for apply:
2025-03-18 21:51:06        INFO        Calibrater::state        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-03-18 21:51:06        INFO        Calibrater::state        The following calibration term is arranged for solve:
2025-03-18 21:51:06        INFO        Calibrater::state        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-03-18 21:51:06        INFO        calibrater::solve        Beginning solve-----------------------------
2025-03-18 21:51:06        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
2025-03-18 21:51:06        INFO        Calibrater::solve        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-03-18 21:51:06        INFO        Calibrater::solve        The following calibration term is arranged for solve:
2025-03-18 21:51:06        INFO        Calibrater::solve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-03-18 21:51:06        INFO                Solving for Mf Mueller
2025-03-18 21:51:06        INFO                For solint = inf, found 84 solution intervals.
2025-03-18 21:51:06        INFO                  Found good Mf Mueller solutions in 84 intervals.
2025-03-18 21:51:06        INFO                Writing solutions to table: output.ms
2025-03-18 21:51:06        INFO        calibrater::solve        Finished solving.
2025-03-18 21:51:07        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
2025-03-18 21:51:07        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
2025-03-18 21:51:07        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
2025-03-18 21:51:07        INFO                Clearing all model records in MS header.
2025-03-18 21:51:07        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-03-18 21:51:07        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
2025-03-18 21:51:08        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
2025-03-18 21:51:08        INFO        Calibrater::        Initializing nominal selection to the whole MS.
2025-03-18 21:51:08        INFO        name::        No table opened.
2025-03-18 21:51:08        INFO        showcache::        The Table Cache has the following 16 entries:
2025-03-18 21:51:08        INFO        showcache::            0: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
2025-03-18 21:51:08        INFO        showcache::            1: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
2025-03-18 21:51:08        INFO        showcache::            2: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
2025-03-18 21:51:08        INFO        showcache::            3: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
2025-03-18 21:51:08        INFO        showcache::            4: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
2025-03-18 21:51:08        INFO        showcache::            5: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
2025-03-18 21:51:08        INFO        showcache::            6: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
2025-03-18 21:51:08        INFO        showcache::            7: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
2025-03-18 21:51:08        INFO        showcache::            8: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
2025-03-18 21:51:08        INFO        showcache::            9: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
2025-03-18 21:51:08        INFO        showcache::            10: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
2025-03-18 21:51:08        INFO        showcache::            11: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
2025-03-18 21:51:08        INFO        showcache::            12: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
2025-03-18 21:51:08        INFO        showcache::            13: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
2025-03-18 21:51:08        INFO        showcache::            14: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
2025-03-18 21:51:08        INFO        showcache::            15: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
2025-03-18 21:51:08        INFO        name::        No table opened.
2025-03-18 21:51:08        INFO        showcache::        The Table Cache is empty.
2025-03-18 21:51:08        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
2025-03-18 21:51:08        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
2025-03-18 21:51:08        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
2025-03-18 21:51:08        INFO                Clearing all model records in MS header.
2025-03-18 21:51:08        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-03-18 21:51:08        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
2025-03-18 21:51:09        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
2025-03-18 21:51:09        INFO        Calibrater::        Initializing nominal selection to the whole MS.
2025-03-18 21:51:09        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
2025-03-18 21:51:09        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
2025-03-18 21:51:09        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-03-18 21:51:09        INFO        calibrater::correct        Beginning correct---------------------------
2025-03-18 21:51:09        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
2025-03-18 21:51:09        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-03-18 21:51:10        INFO        calibrater::correct        Finished correcting.
2025-03-18 21:51:10        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
Cloning into 'casa-build-utils'...
WARNING: You are using pip version 21.2.4; however, version 25.0.1 is available.
You should consider upgrading via the '/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/wheeldirectory/casa6testenv/bin/python3 -m pip install --upgrade pip' command.
/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31071-TTOARM14P310/wheeldirectory/casa6testenv/lib/python3.9/site-packages/urllib3/__init__.py:35: NotOpenSSLWarning: urllib3 v2 only supports OpenSSL 1.1.1+, currently the 'ssl' module is compiled with 'LibreSSL 2.8.3'. See: https://github.com/urllib3/urllib3/issues/3020
  warnings.warn(