/opt/ci_workspace/casa/bamboo/agent5/temp/CASASPECS-DEVTESTPY31258-TTOMLPY312-2-ScriptBuildTask-14909777440020592625.sh: line 2: cd: /opt/casa/data/casa-data-req: No such file or directory
Error response from daemon: No such container: wheel-container-test_153550853
Error response from daemon: No such container: wheel-container-test_153550853
2025-12-05 16:46:20        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1445+09900002_0, spw= 0, nchan= 63
2025-12-05 16:46:20        INFO        GJonesSpline::solve()         Accumulating data for:  field= N5921_2, spw= 0, nchan= 63
2025-12-05 16:46:20        INFO        GJonesSpline::solve()         Number of timestamps in data = 60
2025-12-05 16:46:20        INFO        GJonesSpline::getKnots()         Gridded splinetime = 5280 sec.
2025-12-05 16:46:20        INFO        GJonesSpline::solve()         Number of cubic spline control points = 4
2025-12-05 16:46:20        INFO        GJonesSpline::solve()         Number of cubic spline knots = 8
2025-12-05 16:46:20        INFO        GJonesSpline::solve()         Number of cubic spline segments = 1
2025-12-05 16:46:20        INFO        GJonesSpline::solve()         Fitting amplitude spline.
2025-12-05 16:46:20        INFO        GJonesSpline::solve()         Searching for and correcting phase-wraps on each baseline.
2025-12-05 16:46:20        INFO        GJonesSpline::solve()         Fitting phase spline.
2025-12-05 16:46:20        INFO        GJonesSpline::updateCalTable         Storing solutions in table gainspline
2025-12-05 16:46:20        INFO        calibrater::solve        Finished solving.
2025-12-05 16:46:20        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
2025-12-05 16:46:20        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
2025-12-05 16:46:20        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
2025-12-05 16:46:20        INFO                Clearing all model records in MS header.
2025-12-05 16:46:20        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-12-05 16:46:21        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
2025-12-05 16:46:21        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
2025-12-05 16:46:21        INFO        Calibrater::        Initializing nominal selection to the whole MS.
2025-12-05 16:46:21        INFO        calibrater::specifycal        Beginning specifycal-----------------------
2025-12-05 16:46:21        INFO                Creating G Jones table from specified parameters.
2025-12-05 16:46:21        INFO                Generating 'G' corrections.
2025-12-05 16:46:21        INFO                Specified parameter(s) (per spw and pol) repeated on all antennas.
2025-12-05 16:46:21        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
2025-12-05 16:46:21        INFO                spwId=1 antId=<all> polId=<all> parameter= 3   (ip0,ip1 = [0, 0, 0],[1, 0, 9])
2025-12-05 16:46:21        INFO                Writing solutions to table: testcalout.cal
2025-12-05 16:46:21        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
2025-12-05 16:46:21        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
2025-12-05 16:46:21        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
2025-12-05 16:46:21        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
2025-12-05 16:46:21        INFO                Clearing all model records in MS header.
2025-12-05 16:46:21        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-12-05 16:46:22        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
2025-12-05 16:46:22        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
2025-12-05 16:46:22        INFO        Calibrater::        Initializing nominal selection to the whole MS.
2025-12-05 16:46:22        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
2025-12-05 16:46:22        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
2025-12-05 16:46:22        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-12-05 16:46:22        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
2025-12-05 16:46:22        INFO        Calibrater::setsolve        Arranging to SOLVE:
2025-12-05 16:46:22        INFO        Calibrater::setsolve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-12-05 16:46:22        INFO        Calibrater::state        The following calibration terms are arranged for apply:
2025-12-05 16:46:22        INFO        Calibrater::state        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-12-05 16:46:22        INFO        Calibrater::state        The following calibration term is arranged for solve:
2025-12-05 16:46:22        INFO        Calibrater::state        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-12-05 16:46:22        INFO        calibrater::solve        Beginning solve-----------------------------
2025-12-05 16:46:22        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
2025-12-05 16:46:22        INFO        Calibrater::solve        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-12-05 16:46:22        INFO        Calibrater::solve        The following calibration term is arranged for solve:
2025-12-05 16:46:22        INFO        Calibrater::solve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-12-05 16:46:22        INFO                Solving for Mf Mueller
2025-12-05 16:46:22        INFO                For solint = inf, found 84 solution intervals.
2025-12-05 16:46:23        INFO                  Found good Mf Mueller solutions in 84 intervals.
2025-12-05 16:46:23        INFO                Writing solutions to table: output.ms
2025-12-05 16:46:23        INFO        calibrater::solve        Finished solving.
2025-12-05 16:46:23        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
2025-12-05 16:46:23        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
2025-12-05 16:46:23        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
2025-12-05 16:46:23        INFO                Clearing all model records in MS header.
2025-12-05 16:46:23        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-12-05 16:46:24        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
2025-12-05 16:46:24        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
2025-12-05 16:46:24        INFO        Calibrater::        Initializing nominal selection to the whole MS.
2025-12-05 16:46:24        INFO        name::        No table opened.
2025-12-05 16:46:24        INFO        showcache::        The Table Cache has the following 16 entries:
2025-12-05 16:46:24        INFO        showcache::            0: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
2025-12-05 16:46:24        INFO        showcache::            1: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
2025-12-05 16:46:24        INFO        showcache::            2: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
2025-12-05 16:46:24        INFO        showcache::            3: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
2025-12-05 16:46:24        INFO        showcache::            4: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
2025-12-05 16:46:24        INFO        showcache::            5: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
2025-12-05 16:46:24        INFO        showcache::            6: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
2025-12-05 16:46:24        INFO        showcache::            7: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
2025-12-05 16:46:24        INFO        showcache::            8: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
2025-12-05 16:46:24        INFO        showcache::            9: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
2025-12-05 16:46:24        INFO        showcache::            10: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
2025-12-05 16:46:24        INFO        showcache::            11: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
2025-12-05 16:46:24        INFO        showcache::            12: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
2025-12-05 16:46:24        INFO        showcache::            13: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
2025-12-05 16:46:24        INFO        showcache::            14: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
2025-12-05 16:46:24        INFO        showcache::            15: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
2025-12-05 16:46:24        INFO        name::        No table opened.
2025-12-05 16:46:24        INFO        showcache::        The Table Cache is empty.
2025-12-05 16:46:24        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
2025-12-05 16:46:24        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
2025-12-05 16:46:24        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
2025-12-05 16:46:24        INFO                Clearing all model records in MS header.
2025-12-05 16:46:24        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-12-05 16:46:25        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
2025-12-05 16:46:25        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
2025-12-05 16:46:25        INFO        Calibrater::        Initializing nominal selection to the whole MS.
2025-12-05 16:46:25        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
2025-12-05 16:46:25        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
2025-12-05 16:46:25        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-12-05 16:46:25        INFO        calibrater::correct        Beginning correct---------------------------
2025-12-05 16:46:25        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
2025-12-05 16:46:25        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-12-05 16:46:34        INFO        calibrater::correct        Finished correcting.
2025-12-05 16:46:34        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv

[notice] A new release of pip is available: 23.0.1 -> 25.3
[notice] To update, run: pip3 install --upgrade pip
Cloning into 'casa-build-utils'...