Tools and Tasks tests for Python 3.12

Build: #253 is building

Job: Test Tools Many Linux 2.28 Ubuntu 24.04 Py3.12 was successful

Build log

The build generated 53,251 lines of output.   The output is too long and has been truncated to the last 1,000 lines. Download or view full build log. You can find logs from previous runs here.

08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
08-Apr-2026 09:28:43 test_tool_regionmanager.py::rg_fromtextfile_test::test_read
08-Apr-2026 09:28:43 Read test
08-Apr-2026 09:28:43 PASSED
08-Apr-2026 09:28:43
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageFactory::createImage        Created Temp image  of shape [200, 200] with float valued pixels.
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageRegion::fromRecord        Selected bounding box :
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageRegion::fromRecord+            [50, 31] to [150, 171]  (00:03:20.047, -01.09.00.278 to 23:56:39.950, +01.11.00.303)
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
08-Apr-2026 09:28:43 2026-04-08 13:28:43        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageFactory::createImage        Created Temp image  of shape [200, 200] with float valued pixels.
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageRegion::fromRecord        Selected bounding box :
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageRegion::fromRecord+            [50, 31] to [149, 171]  (00:03:20.047, -01.09.00.278 to 23:56:43.952, +01.11.00.303)
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
08-Apr-2026 09:28:43 2026-04-08 13:28:43        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
08-Apr-2026 09:28:43 test_tool_regionmanager.py::rg_fromtextfile_test::test_rectangle_rotation
08-Apr-2026 09:28:43 Test rectangle region is preserved under coordinate frame switch
08-Apr-2026 09:28:43 PASSED
08-Apr-2026 09:28:43
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageFactory::createImage        Created Temp image  of shape [200, 200] with float valued pixels.
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
08-Apr-2026 09:28:43 test_tool_regionmanager.py::rg_fromtextfile_test::test_rotbox
08-Apr-2026 09:28:43 Test rotbox when specified in pixels (CAS-5723)
08-Apr-2026 09:28:43 PASSED
08-Apr-2026 09:28:43
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageFactory::createImage        Created Temp image  of shape [4, 4, 20] with float valued pixels.
08-Apr-2026 09:28:43 2026-04-08 13:28:43        WARN        AsciiRegionFileParser::_parse         line# 1: Error converting world region to lattice region which probably indicates the region lies outside of the image. This region will be ignored.The related message is: LCBox::LCBox - blc [0] must be <= trc [-99889]
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        AsciiRegionFileParser::_parse        Combined 0 image regions (which excludes any annotation regions)
08-Apr-2026 09:28:43 2026-04-08 13:28:43        SEVERE        regionmanager::selectedchannels        Exception Reported: Exception: The specified spectral range range=[1315MHz, 1315.1MHz] does not intersect the image spectral range..
08-Apr-2026 09:28:43 2026-04-08 13:28:43        SEVERE        regionmanager::selectedchannels+        ... thrown by std::vector<unsigned int, std::allocator<unsigned int> > casa::CasacRegionManager::_spectralRangeFromRangeFormat(casacore::uInt&, const casacore::String&, const casacore::IPosition&) const at File: /source/casa6/casatools/src/code/imageanalysis/Regions/CasacRegionManager.cc, line: 1013
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        name::        No table opened.
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        name::        No table opened.
08-Apr-2026 09:28:43 test_tool_regionmanager.py::rg_selectedchannels_test::test_no_overlap
08-Apr-2026 09:28:43 Test selection outside of image
08-Apr-2026 09:28:43 PASSED
08-Apr-2026 09:28:43
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageFactory::createImage        Created Temp image  of shape [4, 4, 4] with float valued pixels.
08-Apr-2026 09:28:43 2026-04-08 13:28:43        SEVERE        regionmanager::selectedchannels        Exception Reported: Exception: Associated coordinate system has no spectral axis.
08-Apr-2026 09:28:43 2026-04-08 13:28:43        SEVERE        regionmanager::selectedchannels+        ... thrown by std::vector<long int> casac::regionmanager::selectedchannels(const string&, const std::vector<long int>&) at File: src/tools/regionmanager/regionmanager_cmpt.cc, line: 686
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        name::        No table opened.
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        name::        No table opened.
08-Apr-2026 09:28:43 test_tool_regionmanager.py::rg_selectedchannels_test::test_no_spectral_axis
08-Apr-2026 09:28:43 Test no spectral axis throws exception
08-Apr-2026 09:28:43 PASSED
08-Apr-2026 09:28:43
08-Apr-2026 09:28:43 2026-04-08 13:28:43        INFO        ImageFactory::createImage        Created Temp image  of shape [4, 4, 20] with float valued pixels.
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        AsciiRegionFileParser::_parse        Combined 1 image regions (which excludes any annotation regions)
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        AsciiRegionFileParser::_parse        The specified region will select all pixels that are included in the region. Full pixels will be included even when they are only partially covered by the region(s).
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        name::        No table opened.
08-Apr-2026 09:28:43 2026-04-08 13:28:44        INFO        name::        No table opened.
08-Apr-2026 09:28:43 test_tool_regionmanager.py::rg_selectedchannels_test::test_range
08-Apr-2026 09:28:43 Test range
08-Apr-2026 09:28:43 PASSED
08-Apr-2026 09:28:43
08-Apr-2026 09:28:43 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_regionmanager/nose.xml -
08-Apr-2026 09:28:43 ======================== 34 passed, 1 warning in 18.96s ========================
08-Apr-2026 09:28:44 ============================= test session starts ==============================
08-Apr-2026 09:28:44 platform linux -- Python 3.12.9, pytest-9.0.3, pluggy-1.6.0 -- /home/casatest/venv/bin/python
08-Apr-2026 09:28:44 cachedir: .pytest_cache
08-Apr-2026 09:28:44 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater
08-Apr-2026 09:28:44 configfile: pytest.ini
08-Apr-2026 09:28:44 collecting ... collected 24 items
08-Apr-2026 09:28:44
08-Apr-2026 09:28:44
08-Apr-2026 09:28:46 2026-04-08 13:28:47        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:28:46 2026-04-08 13:28:47        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:28:46 2026-04-08 13:28:47        INFO                Clearing all model records in MS header.
08-Apr-2026 09:28:47 2026-04-08 13:28:47        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:28:48 2026-04-08 13:28:48        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:28:48 2026-04-08 13:28:49        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:28:48 2026-04-08 13:28:49        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:28:48 2026-04-08 13:28:49        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:28:48 2026-04-08 13:28:49        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:28:48 2026-04-08 13:28:49        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:28:48 2026-04-08 13:28:49        INFO        calibrater::corrupt        Beginning corrupt---------------------------
08-Apr-2026 09:28:48 2026-04-08 13:28:49        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:28:48 2026-04-08 13:28:49        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:28:50 2026-04-08 13:28:51        INFO        calibrater::corrupt        Finished corrupting.
08-Apr-2026 09:28:50 2026-04-08 13:28:51        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:28:51 test_tool_calibrater.py::calibrater_test::test_activityRecord
08-Apr-2026 09:28:51 Check that using the calibrater to modify the vis shows in the activity record 
08-Apr-2026 09:28:51 PASSED
08-Apr-2026 09:28:51
08-Apr-2026 09:28:51 2026-04-08 13:28:51        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:28:51 2026-04-08 13:28:51        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:28:51 2026-04-08 13:28:51        INFO                Clearing all model records in MS header.
08-Apr-2026 09:28:51 2026-04-08 13:28:51        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:28:52 2026-04-08 13:28:53        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:28:52 2026-04-08 13:28:53        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:28:52 2026-04-08 13:28:53        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:28:52 2026-04-08 13:28:53        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:28:52 2026-04-08 13:28:53        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:28:53 2026-04-08 13:28:53        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:28:53 2026-04-08 13:28:53        INFO        calibrater::corrupt        Beginning corrupt---------------------------
08-Apr-2026 09:28:53 2026-04-08 13:28:53        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:28:53 2026-04-08 13:28:53        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:28:55 2026-04-08 13:28:56        INFO        calibrater::corrupt        Finished corrupting.
08-Apr-2026 09:28:57 2026-04-08 13:28:57        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:28:57 test_tool_calibrater.py::calibrater_test::test_corruptCal
08-Apr-2026 09:28:57 Check that the MS is corrupted using the cal table 
08-Apr-2026 09:28:57 PASSED
08-Apr-2026 09:28:57
08-Apr-2026 09:28:57 2026-04-08 13:28:57        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:28:57 2026-04-08 13:28:57        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:28:57 2026-04-08 13:28:57        INFO                Clearing all model records in MS header.
08-Apr-2026 09:28:57 2026-04-08 13:28:57        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:28:58 2026-04-08 13:28:59        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:28:58 2026-04-08 13:28:59        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:28:58 2026-04-08 13:28:59        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:28:59 2026-04-08 13:28:59        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:28:59 test_tool_calibrater.py::calibrater_test::test_createEmpty
08-Apr-2026 09:28:59 Check that an empty cal table can be created
08-Apr-2026 09:28:59 PASSED
08-Apr-2026 09:28:59
08-Apr-2026 09:28:59 2026-04-08 13:28:59        INFO        name::        No table opened.
08-Apr-2026 09:28:59 2026-04-08 13:28:59        INFO        showcache::        The Table Cache is empty.
08-Apr-2026 09:28:59 2026-04-08 13:28:59        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:28:59 2026-04-08 13:28:59        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:28:59 2026-04-08 13:28:59        INFO                Clearing all model records in MS header.
08-Apr-2026 09:28:59 2026-04-08 13:28:59        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:00 2026-04-08 13:29:01        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        name::        No table opened.
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::        The Table Cache has the following 16 entries:
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            0: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            1: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            2: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            3: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            4: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            5: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            6: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            7: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            8: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            9: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            10: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            11: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            12: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            13: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            14: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        showcache::            15: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
08-Apr-2026 09:29:01 2026-04-08 13:29:01        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:01 test_tool_calibrater.py::calibrater_test::test_done
08-Apr-2026 09:29:01 Check that done closes the active calibrator tool 
08-Apr-2026 09:29:01 PASSED
08-Apr-2026 09:29:01
08-Apr-2026 09:29:01 2026-04-08 13:29:01        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:01 2026-04-08 13:29:01        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:02 2026-04-08 13:29:03        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:02 2026-04-08 13:29:03        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:02 2026-04-08 13:29:03        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:02 2026-04-08 13:29:03        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:02 2026-04-08 13:29:03        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:03 2026-04-08 13:29:03        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:03 2026-04-08 13:29:03        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:03 2026-04-08 13:29:03        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:03 2026-04-08 13:29:03        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:05 2026-04-08 13:29:06        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:29:06 2026-04-08 13:29:06        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
08-Apr-2026 09:29:06 2026-04-08 13:29:06        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:06 2026-04-08 13:29:06        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:06 2026-04-08 13:29:06        INFO        calibrater::initcalset        Beginning initcalset------------------------
08-Apr-2026 09:29:06 2026-04-08 13:29:06        INFO        VisSet::VisSet        Initializing MODEL_DATA (to unity) and CORRECTED_DATA (to DATA)
08-Apr-2026 09:29:06 2026-04-08 13:29:07        INFO        VisSet::VisSet        Initialized 328860 rows.
08-Apr-2026 09:29:08 2026-04-08 13:29:08        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
08-Apr-2026 09:29:08 test_tool_calibrater.py::calibrater_test::test_reinitModel
08-Apr-2026 09:29:08 Check that initcalset will reset the CORRECTED_DATA to unity 
08-Apr-2026 09:29:08 PASSED
08-Apr-2026 09:29:08
08-Apr-2026 09:29:08 2026-04-08 13:29:08        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:08 2026-04-08 13:29:08        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:08 2026-04-08 13:29:08        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:08 2026-04-08 13:29:08        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:09 2026-04-08 13:29:10        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:09 2026-04-08 13:29:10        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:09 2026-04-08 13:29:10        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:09 2026-04-08 13:29:10        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:09 2026-04-08 13:29:10        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        Calibrater::setsolve        Arranging to SOLVE:
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        Calibrater::setsolve        .   Mf Mueller: table=gaincaltest2.ms.G0 append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        calibrater::solve        Beginning solve-----------------------------
08-Apr-2026 09:29:10 2026-04-08 13:29:10        SEVERE        Calibrater::solve        Caught exception: Please run setsolve before attempting to solve.
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        Calibrater::solve        Reseting entire solve/apply state.
08-Apr-2026 09:29:10 2026-04-08 13:29:10        SEVERE                Exception Reported: Error in Calibrater::solve.
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        Calibrater::correct2 (VI2/VB2)        .   (None)
08-Apr-2026 09:29:10 2026-04-08 13:29:10        SEVERE        Calibrater::correct2 (VI2/VB2)        Caught exception: Nothing to Apply
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        Calibrater::correct2 (VI2/VB2)        Resetting all calibration application settings.
08-Apr-2026 09:29:10 2026-04-08 13:29:10        SEVERE                Exception Reported: Error in Calibrater::correct.
08-Apr-2026 09:29:10 2026-04-08 13:29:10        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:10 test_tool_calibrater.py::calibrater_test::test_resetSolveApply
08-Apr-2026 09:29:10 Check that the reset function can clear set apply and solves 
08-Apr-2026 09:29:10 PASSED
08-Apr-2026 09:29:10
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:10 2026-04-08 13:29:10        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:10 2026-04-08 13:29:11        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:11 2026-04-08 13:29:12        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:12 2026-04-08 13:29:12        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:12 2026-04-08 13:29:12        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:12 2026-04-08 13:29:13        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
08-Apr-2026 09:29:12 2026-04-08 13:29:13        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:12 2026-04-08 13:29:13        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
08-Apr-2026 09:29:12 2026-04-08 13:29:13        INFO        Calibrater::selectvis+         Selecting on spw: '1'
08-Apr-2026 09:29:12 2026-04-08 13:29:13        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 82215
08-Apr-2026 09:29:12 2026-04-08 13:29:13        INFO        Calibrater::selectvis        Frequency selection:
08-Apr-2026 09:29:12 2026-04-08 13:29:13        INFO        Calibrater::selectvis        .  Spw 1:0~7 (8 channels, step by 1)
08-Apr-2026 09:29:12 2026-04-08 13:29:13        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
08-Apr-2026 09:29:12 2026-04-08 13:29:13        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:13 test_tool_calibrater.py::calibrater_test::test_returnDict
08-Apr-2026 09:29:13 Check that the returndict function gives a dictonary with the expected keys 
08-Apr-2026 09:29:13 PASSED
08-Apr-2026 09:29:13
08-Apr-2026 09:29:13 2026-04-08 13:29:14        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:13 2026-04-08 13:29:14        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:13 2026-04-08 13:29:14        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:13 2026-04-08 13:29:14        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        Calibrater::selectvis+         Selecting on antenna/baseline: '0'
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 65772
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:15 2026-04-08 13:29:15        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:17 2026-04-08 13:29:17        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:29:17 2026-04-08 13:29:18        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:17 test_tool_calibrater.py::calibrater_test::test_selectVisBaseline
08-Apr-2026 09:29:17 Check that selectvis properly selects baseline/antenna
08-Apr-2026 09:29:17 65772 65772
08-Apr-2026 09:29:17 263088 263088
08-Apr-2026 09:29:17 PASSED
08-Apr-2026 09:29:17
08-Apr-2026 09:29:18 2026-04-08 13:29:19        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:18 2026-04-08 13:29:19        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:18 2026-04-08 13:29:19        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:18 2026-04-08 13:29:19        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        Calibrater::selectvis+         Selecting on field: '0'
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 172260
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:20 2026-04-08 13:29:20        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:20 2026-04-08 13:29:21        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:20 2026-04-08 13:29:21        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:20 2026-04-08 13:29:21        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:20 2026-04-08 13:29:21        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:22 2026-04-08 13:29:22        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:29:22 2026-04-08 13:29:23        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:22 test_tool_calibrater.py::calibrater_test::test_selectVisField
08-Apr-2026 09:29:22 Check that the field is properly selected by selectvis
08-Apr-2026 09:29:22 PASSED
08-Apr-2026 09:29:22
08-Apr-2026 09:29:23 2026-04-08 13:29:24        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:23 2026-04-08 13:29:24        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:23 2026-04-08 13:29:24        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:23 2026-04-08 13:29:24        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:24 2026-04-08 13:29:25        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        Calibrater::selectvis+         Selecting on intent: '*AMPLI*'
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        Calibrater::selectvis        Selection did not drop any rows
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:25 2026-04-08 13:29:25        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:27 2026-04-08 13:29:28        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:29:27 2026-04-08 13:29:28        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:27 test_tool_calibrater.py::calibrater_test::test_selectVisIntent
08-Apr-2026 09:29:27 Check that the intent is properly selected by selectvis
08-Apr-2026 09:29:27 PASSED
08-Apr-2026 09:29:27
08-Apr-2026 09:29:28 2026-04-08 13:29:29        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:28 2026-04-08 13:29:29        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:28 2026-04-08 13:29:29        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:28 2026-04-08 13:29:29        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        Calibrater::selectvis+         Selecting by observation IDs: '0'
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 318870
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:30 2026-04-08 13:29:30        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:30 2026-04-08 13:29:31        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:30 2026-04-08 13:29:31        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:30 2026-04-08 13:29:31        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:30 2026-04-08 13:29:31        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:33 2026-04-08 13:29:33        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:29:33 2026-04-08 13:29:33        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:33 test_tool_calibrater.py::calibrater_test::test_selectVisObs
08-Apr-2026 09:29:33 PASSED
08-Apr-2026 09:29:33
08-Apr-2026 09:29:34 2026-04-08 13:29:34        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:34 2026-04-08 13:29:34        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:34 2026-04-08 13:29:34        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:34 2026-04-08 13:29:34        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:35 2026-04-08 13:29:35        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        Calibrater::selectvis+         Selecting on scan: '2'
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 15660
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:35 2026-04-08 13:29:36        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:29:36 2026-04-08 13:29:37        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:36 test_tool_calibrater.py::calibrater_test::test_selectVisScan
08-Apr-2026 09:29:36 Check tht the scan is properly selected by selectvis
08-Apr-2026 09:29:36 PASSED
08-Apr-2026 09:29:36
08-Apr-2026 09:29:37 2026-04-08 13:29:37        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:37 2026-04-08 13:29:37        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:37 2026-04-08 13:29:37        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:37 2026-04-08 13:29:37        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::selectvis+         Selecting on spw: '0'
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 82215
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::selectvis        Frequency selection:
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::selectvis        .  Spw 0:0~7 (8 channels, step by 1)
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:38 2026-04-08 13:29:39        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:39 2026-04-08 13:29:40        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:29:40 2026-04-08 13:29:40        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:40 test_tool_calibrater.py::calibrater_test::test_selectVisSpw
08-Apr-2026 09:29:40 Check that spw is properly selected by selectvis
08-Apr-2026 09:29:40 PASSED
08-Apr-2026 09:29:40
08-Apr-2026 09:29:41 2026-04-08 13:29:41        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:41 2026-04-08 13:29:41        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:41 2026-04-08 13:29:41        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:41 2026-04-08 13:29:41        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:42 2026-04-08 13:29:42        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        Calibrater::selectvis+         Selecting on time: '>04:38:23'
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 313200
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:42 2026-04-08 13:29:43        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:45 2026-04-08 13:29:46        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:29:46 2026-04-08 13:29:46        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:46 test_tool_calibrater.py::calibrater_test::test_selectVisTime
08-Apr-2026 09:29:46 Check that time is properly selected by selectvis
08-Apr-2026 09:29:46 PASSED
08-Apr-2026 09:29:46
08-Apr-2026 09:29:46 2026-04-08 13:29:47        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:46 2026-04-08 13:29:47        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:46 2026-04-08 13:29:47        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:46 2026-04-08 13:29:47        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:47 2026-04-08 13:29:48        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:48 2026-04-08 13:29:48        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:48 2026-04-08 13:29:48        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:48 2026-04-08 13:29:48        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
08-Apr-2026 09:29:48 2026-04-08 13:29:48        INFO        calibrater::reset        Reseting solve/apply state
08-Apr-2026 09:29:48 2026-04-08 13:29:48        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
08-Apr-2026 09:29:48 2026-04-08 13:29:48        INFO        Calibrater::selectvis+         Selecting on uvrange: '> 500000lambda'
08-Apr-2026 09:29:48 2026-04-08 13:29:49        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 103321
08-Apr-2026 09:29:48 2026-04-08 13:29:49        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
08-Apr-2026 09:29:48 2026-04-08 13:29:49        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
08-Apr-2026 09:29:48 2026-04-08 13:29:49        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:29:48 2026-04-08 13:29:49        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:29:48 2026-04-08 13:29:49        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:48 2026-04-08 13:29:49        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:29:48 2026-04-08 13:29:49        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:48 2026-04-08 13:29:49        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:29:50 2026-04-08 13:29:51        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:29:51 2026-04-08 13:29:51        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:51 test_tool_calibrater.py::calibrater_test::test_selectVisUVrange
08-Apr-2026 09:29:51 Check that selectvis properly selects for uvrange
08-Apr-2026 09:29:51 PASSED
08-Apr-2026 09:29:51
08-Apr-2026 09:29:51 2026-04-08 13:29:52        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:51 2026-04-08 13:29:52        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:51 2026-04-08 13:29:52        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:51 2026-04-08 13:29:52        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:52 2026-04-08 13:29:53        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        calibrater::parsecallibfile        Beginning parsecallibfile-)-------
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        calibrater::setcallib        Beginning setcallib---------
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        Calibrater::setcallib2(callib)        Arranging to APPLY:
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO                .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO                .   0:
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        +             MS: obs= scan= fld= intent= spw=
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        +             CT: tinterp=linear finterp=
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        +                 obsmap=[]         scanmap=[]         fldmap=[]
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        +                 spwmap=[]         antmap=[]
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        calibrater::corrupt        Beginning corrupt---------------------------
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:53 2026-04-08 13:29:53        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
08-Apr-2026 09:29:55 2026-04-08 13:29:56        INFO        calibrater::corrupt        Finished corrupting.
08-Apr-2026 09:29:56 2026-04-08 13:29:56        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:56 test_tool_calibrater.py::calibrater_test::test_setCalLib
08-Apr-2026 09:29:56 Check that a provided cal table can be used to corrupt the MODEL_DATA 
08-Apr-2026 09:29:56 PASSED
08-Apr-2026 09:29:56
08-Apr-2026 09:29:56 2026-04-08 13:29:57        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:56 2026-04-08 13:29:57        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:29:56 2026-04-08 13:29:57        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:56 2026-04-08 13:29:57        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:57 2026-04-08 13:29:58        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:58 2026-04-08 13:29:58        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:29:58 2026-04-08 13:29:58        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:58 2026-04-08 13:29:58        INFO        Calibrater::setCorrDepFlags        Setting correlation dependent flags = True
08-Apr-2026 09:29:58 2026-04-08 13:29:58        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:58 test_tool_calibrater.py::calibrater_test::test_setCorrDepFlags
08-Apr-2026 09:29:58 Check that corrdepflags will be checked 
08-Apr-2026 09:29:58 PASSED
08-Apr-2026 09:29:58
08-Apr-2026 09:29:58 2026-04-08 13:29:58        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:29:58 2026-04-08 13:29:58        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
08-Apr-2026 09:29:58 2026-04-08 13:29:58        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:58 2026-04-08 13:29:58        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:58 2026-04-08 13:29:58        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:58 2026-04-08 13:29:59        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
08-Apr-2026 09:29:58 2026-04-08 13:29:59        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:58 2026-04-08 13:29:59        INFO        calibrater::smooth        Beginning smooth--(MSSelection version)-------
08-Apr-2026 09:29:58 2026-04-08 13:29:59        INFO        Calibrater::smooth        Beginning smoothing/interpolating method.
08-Apr-2026 09:29:58 2026-04-08 13:29:59        WARN                Found pre-v4.1 caltable (/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/ngc5921.gcal); attempting to update...
08-Apr-2026 09:29:58 2026-04-08 13:29:59        WARN        +        SUCCEEDED: trivial OBSERVATION/OBSERVATION_ID have been added.
08-Apr-2026 09:29:58 2026-04-08 13:29:59        INFO        Calibrater::smooth        Storing result in testcalout.cal
08-Apr-2026 09:29:58 2026-04-08 13:29:59        INFO                Writing solutions to table: testcalout.cal
08-Apr-2026 09:29:58 2026-04-08 13:29:59        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:29:58 test_tool_calibrater.py::calibrater_test::test_smoothedCalTables
08-Apr-2026 09:29:58 Check that the smooth command creates a smoothed cal table 
08-Apr-2026 09:29:58 PASSED
08-Apr-2026 09:29:58
08-Apr-2026 09:29:59 2026-04-08 13:29:59        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO                Clearing all model records in MS header.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        calibrater::setsolvebandpoly        Beginning setsolvebandpoly--(MSSelection version)-------
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        Calibrater::setsolvebandpoly        Arranging to SOLVE:
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        Calibrater::setsolvebandpoly        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        calibrater::solve        Beginning solve-----------------------------
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        Calibrater::solve        .   (None)
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        Calibrater::solve        The following calibration term is arranged for solve:
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        Calibrater::solve        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        Calibrater::solve        Solving for BPOLY
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO                For solint = inf, found 7 solution intervals.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.059507/0.0681762/0.0765806
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.336529/0.750193/1.6779
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0630839/0.0689061/0.0756384
08-Apr-2026 09:29:59 2026-04-08 13:29:59        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.279482/0.74979/1.59355
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0672607/0.089081/0.111106
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.31075/3.32219/4.29305
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0652822/0.0889198/0.116912
08-Apr-2026 09:29:59 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.44269/3.26616/4.3519
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350681/0.587724/0.894558
08-Apr-2026 09:30:00 2026-04-08 13:30:00        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:02 2026-04-08 13:30:02        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.296/59.0077/111.454
08-Apr-2026 09:30:02 2026-04-08 13:30:02        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:02 2026-04-08 13:30:02        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.354236/0.581733/0.988903
08-Apr-2026 09:30:02 2026-04-08 13:30:02        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 17.2419/56.3249/117.264
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0850169/0.113968/0.15276
08-Apr-2026 09:30:03 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.67561/5.33243/8.17296
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0784236/0.114345/0.159908
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.46392/5.27232/7.20673
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0703936/0.102501/0.131676
08-Apr-2026 09:30:04 2026-04-08 13:30:04        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.98728/4.47977/5.84264
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0751114/0.102994/0.138529
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.18629/4.47068/5.8941
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350626/0.604424/0.974388
08-Apr-2026 09:30:04 2026-04-08 13:30:05        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:06 2026-04-08 13:30:07        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.2542/59.3511/120.719
08-Apr-2026 09:30:06 2026-04-08 13:30:07        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:06 2026-04-08 13:30:07        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.382806/0.598119/0.941571
08-Apr-2026 09:30:06 2026-04-08 13:30:07        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 20.0637/57.2112/118.101
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0750931/0.0978998/0.125897
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.72932/3.98787/5.33715
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0658572/0.0972125/0.140794
08-Apr-2026 09:30:08 2026-04-08 13:30:08        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
08-Apr-2026 09:30:08 2026-04-08 13:30:09        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.97943/3.97841/5.72903
08-Apr-2026 09:30:08 2026-04-08 13:30:09        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
08-Apr-2026 09:30:08 2026-04-08 13:30:09        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
08-Apr-2026 09:30:08 2026-04-08 13:30:09        INFO        Calibrater::solve          Found good BPOLY solutions in 7 slots.
08-Apr-2026 09:30:08 2026-04-08 13:30:09        INFO        calibrater::solve        Finished solving.
08-Apr-2026 09:30:08 2026-04-08 13:30:09        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
08-Apr-2026 09:30:08 test_tool_calibrater.py::calibrater_test::test_solveBandpass
08-Apr-2026 09:30:08 Check that solve band poly creates the output table
08-Apr-2026 09:30:08 PASSED
08-Apr-2026 09:30:08
08-Apr-2026 09:30:08 2026-04-08 13:30:09        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
08-Apr-2026 09:30:08 2026-04-08 13:30:09        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
08-Apr-2026 09:30:08 2026-04-08 13:30:09        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
08-Apr-2026 09:30:08 2026-04-08 13:30:09        INFO                Clearing all model records in MS header.
08-Apr-2026 09:30:08 2026-04-08 13:30:09        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        calibrater::setsolvegainspline        Beginning setsolvegainspline--(MSSelection version)-------
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        Calibrater::setsolvegainspline        Arranging to SOLVE:
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        Calibrater::setsolvegainspline        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        calibrater::solve        Beginning solve-----------------------------
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        Calibrater::solve        .   (None)
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        Calibrater::solve        The following calibration term is arranged for solve:
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        Calibrater::solve        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Fitting time-dependent cubic splines.
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Solving for phase splines with splinetime= 10800
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Solving for amplitude splines with splinetime= 10800
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1331+30500002_0, spw= 0, nchan= 63
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1445+09900002_0, spw= 0, nchan= 63
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Accumulating data for:  field= N5921_2, spw= 0, nchan= 63
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Number of timestamps in data = 60
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::getKnots()         Gridded splinetime = 5280 sec.
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Number of cubic spline control points = 4
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Number of cubic spline knots = 8
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Number of cubic spline segments = 1
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Fitting amplitude spline.
08-Apr-2026 09:30:09 test_tool_calibrater.py::calibrater_test::test_solveGainspline  DPBTRF ;problem leading minor           23
08-Apr-2026 09:30:09 DPBTRF ;problem leading minor           23
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Searching for and correcting phase-wraps on each baseline.
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::solve()         Fitting phase spline.
08-Apr-2026 09:30:09 DPBTRF ;problem leading minor           22
08-Apr-2026 09:30:09 DPBTRF ;problem leading minor           22
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        GJonesSpline::updateCalTable         Storing solutions in table gainspline
08-Apr-2026 09:30:09 2026-04-08 13:30:09        INFO        calibrater::solve        Finished solving.
08-Apr-2026 09:30:09 2026-04-08 13:30:09        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
08-Apr-2026 09:30:09
08-Apr-2026 09:30:09 Check that solve gain spline creates the output table 
08-Apr-2026 09:30:09 PASSED
08-Apr-2026 09:30:09
08-Apr-2026 09:30:09 2026-04-08 13:30:10        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:30:09 2026-04-08 13:30:10        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:30:09 2026-04-08 13:30:10        INFO                Clearing all model records in MS header.
08-Apr-2026 09:30:09 2026-04-08 13:30:10        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:30:10 2026-04-08 13:30:11        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:30:11 2026-04-08 13:30:11        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:30:11 2026-04-08 13:30:11        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:30:11 2026-04-08 13:30:11        INFO        calibrater::specifycal        Beginning specifycal-----------------------
08-Apr-2026 09:30:11 2026-04-08 13:30:11        INFO                Creating G Jones table from specified parameters.
08-Apr-2026 09:30:11 2026-04-08 13:30:11        INFO                Generating 'G' corrections.
08-Apr-2026 09:30:11 2026-04-08 13:30:11        INFO                Specified parameter(s) (per spw and pol) repeated on all antennas.
08-Apr-2026 09:30:11 2026-04-08 13:30:11        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
08-Apr-2026 09:30:11 2026-04-08 13:30:11        INFO                spwId=1 antId=<all> polId=<all> parameter= 3   (ip0,ip1 = [0, 0, 0],[1, 0, 9])
08-Apr-2026 09:30:11 2026-04-08 13:30:11        INFO                Writing solutions to table: testcalout.cal
08-Apr-2026 09:30:11 2026-04-08 13:30:11        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:30:11 test_tool_calibrater.py::calibrater_test::test_specifyCal
08-Apr-2026 09:30:11 Check that specifycal can set values for specific spws and antennas
08-Apr-2026 09:30:11 PASSED
08-Apr-2026 09:30:11
08-Apr-2026 09:30:11 2026-04-08 13:30:12        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
08-Apr-2026 09:30:11 2026-04-08 13:30:12        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
08-Apr-2026 09:30:11 2026-04-08 13:30:12        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:30:11 2026-04-08 13:30:12        INFO                Clearing all model records in MS header.
08-Apr-2026 09:30:11 2026-04-08 13:30:12        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:30:12 2026-04-08 13:30:13        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::setsolve        Arranging to SOLVE:
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::setsolve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::state        The following calibration terms are arranged for apply:
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::state        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::state        The following calibration term is arranged for solve:
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::state        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        calibrater::solve        Beginning solve-----------------------------
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::solve        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::solve        The following calibration term is arranged for solve:
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO        Calibrater::solve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
08-Apr-2026 09:30:13 2026-04-08 13:30:13        INFO                Solving for Mf Mueller
08-Apr-2026 09:30:13 2026-04-08 13:30:14        INFO                For solint = inf, found 84 solution intervals.
08-Apr-2026 09:30:15 2026-04-08 13:30:15        INFO                  Found good Mf Mueller solutions in 84 intervals.
08-Apr-2026 09:30:15 2026-04-08 13:30:15        INFO                Writing solutions to table: output.ms
08-Apr-2026 09:30:15 2026-04-08 13:30:15        INFO        calibrater::solve        Finished solving.
08-Apr-2026 09:30:15 2026-04-08 13:30:15        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
08-Apr-2026 09:30:15 test_tool_calibrater.py::calibrater_test::test_standardPath
08-Apr-2026 09:30:15 open setapply setsolve state solve close 
08-Apr-2026 09:30:15 PASSED
08-Apr-2026 09:30:15
08-Apr-2026 09:30:15 2026-04-08 13:30:16        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:30:15 2026-04-08 13:30:16        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:30:15 2026-04-08 13:30:16        INFO                Clearing all model records in MS header.
08-Apr-2026 09:30:15 2026-04-08 13:30:16        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:30:16 2026-04-08 13:30:17        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        name::        No table opened.
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::        The Table Cache has the following 16 entries:
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            0: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            1: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            2: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            3: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            4: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            5: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            6: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            7: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            8: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            9: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            10: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            11: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            12: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            13: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            14: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::            15: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        name::        No table opened.
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        showcache::        The Table Cache is empty.
08-Apr-2026 09:30:17 2026-04-08 13:30:17        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:30:17 test_tool_calibrater.py::calibrater_test::test_takesMs
08-Apr-2026 09:30:17 Check that the calibrater tool can open and close an MS 
08-Apr-2026 09:30:17 PASSED
08-Apr-2026 09:30:17
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO                Clearing all model records in MS header.
08-Apr-2026 09:30:17 2026-04-08 13:30:17        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
08-Apr-2026 09:30:18 2026-04-08 13:30:19        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
08-Apr-2026 09:30:18 2026-04-08 13:30:19        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
08-Apr-2026 09:30:18 2026-04-08 13:30:19        INFO        Calibrater::        Initializing nominal selection to the whole MS.
08-Apr-2026 09:30:18 2026-04-08 13:30:19        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
08-Apr-2026 09:30:18 2026-04-08 13:30:19        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
08-Apr-2026 09:30:19 2026-04-08 13:30:19        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:30:19 2026-04-08 13:30:19        INFO        calibrater::correct        Beginning correct---------------------------
08-Apr-2026 09:30:19 2026-04-08 13:30:19        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
08-Apr-2026 09:30:19 2026-04-08 13:30:19        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
08-Apr-2026 09:30:21 2026-04-08 13:30:22        INFO        calibrater::correct        Finished correcting.
08-Apr-2026 09:30:21 2026-04-08 13:30:22        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
08-Apr-2026 09:30:21 test_tool_calibrater.py::calibrater_test::test_writeToCorrected
08-Apr-2026 09:30:21 Check that the tool writes to the CORRECTED_DATA column 
08-Apr-2026 09:30:22 PASSED
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml -
08-Apr-2026 09:30:22 =================== 24 passed, 1 warning in 97.88s (0:01:37) ===================
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calanalysis/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_coordsys/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_coordsys
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_coordsys/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_coordsys/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_ms/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_synthesisutils/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_synthesisutils
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_synthesisutils/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_synthesisutils/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_collapse/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_collapse
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_collapse/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_collapse/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_deviation/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_linearmosaic/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_linearmosaic
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_linearmosaic/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_linearmosaic/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_subimage/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_regrid/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_rebin/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_rebin
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_rebin/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_rebin/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_logsink/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_logsink
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_logsink/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_logsink/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_imagepol/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_measures/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_measures/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_componentlist/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_componentlist
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_componentlist/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_componentlist/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_statistics/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm_toms/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm_toms
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_sdm_toms/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm_toms/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_regionmanager/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager/
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
08-Apr-2026 09:30:22 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater
08-Apr-2026 09:30:22
08-Apr-2026 09:30:22 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
08-Apr-2026 09:30:24 total 77444
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest   160659 Apr  8 13:12 casatestutils-6.7.5.13-py3-none-any.whl
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest 78969046 Apr  8 13:12 casatools-6.7.5.13-cp312-cp312-manylinux_2_28_x86_64.whl
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     4340 Apr  8 13:30 test_tool_agentflagger.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1006 Apr  8 13:30 test_tool_calanalysis.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2656 Apr  8 13:30 test_tool_calibrater.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      687 Apr  8 13:30 test_tool_componentlist.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     7467 Apr  8 13:30 test_tool_coordsys.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest    12935 Apr  8 13:30 test_tool_image.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2220 Apr  8 13:30 test_tool_image_collapse.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1048 Apr  8 13:30 test_tool_image_deviation.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2575 Apr  8 13:30 test_tool_image_fitprofile.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1178 Apr  8 13:30 test_tool_image_pbcor.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      883 Apr  8 13:30 test_tool_image_pv.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1006 Apr  8 13:30 test_tool_image_rebin.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1336 Apr  8 13:30 test_tool_image_regrid.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1641 Apr  8 13:30 test_tool_image_statistics.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      765 Apr  8 13:30 test_tool_image_subimage.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      890 Apr  8 13:30 test_tool_image_transpose.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1966 Apr  8 13:30 test_tool_imagepol.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      839 Apr  8 13:30 test_tool_linearmosaic.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      669 Apr  8 13:30 test_tool_logsink.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      549 Apr  8 13:30 test_tool_measures.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     5224 Apr  8 13:30 test_tool_ms.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      367 Apr  8 13:30 test_tool_ms_createmultims.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2095 Apr  8 13:30 test_tool_ms_statwt.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     8633 Apr  8 13:30 test_tool_msmetadata.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      359 Apr  8 13:30 test_tool_msuvbinner.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1171 Apr  8 13:30 test_tool_quanta.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     3977 Apr  8 13:30 test_tool_regionmanager.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2072 Apr  8 13:30 test_tool_sdm.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2096 Apr  8 13:30 test_tool_sdm_toms.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      670 Apr  8 13:30 test_tool_simulator.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1717 Apr  8 13:30 test_tool_synthesisutils.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1462 Apr  8 13:30 test_tool_table.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1717 Apr  8 13:30 test_tool_vpmanager.xml
08-Apr-2026 09:30:24 Finished task 'Test wheel' with result: Success
08-Apr-2026 09:30:24 Starting task 'Tar work directory' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
08-Apr-2026 09:30:24
Beginning to execute external process for build 'CASASPECS - Master Test 3.12 - Test Tools Many Linux 2.28 Ubuntu 24.04 Py3.12 #253 (CASASPECS-MASTERTESTPY312-TTU2P-253)'
... running command line:
/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTU2P-253-ScriptBuildTask-7328717770067396062.sh
... in: /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P
bamboo_planRepository_1_branch=master
bamboo_QUOTE="
bamboo_planRepository_2_branch=master
bamboo_planRepository_1_branchDisplayName=master
bamboo_repository_revision_number=61b4f7fa0783c3d03b597000f81f66ef9b23d37c
bamboo_twine_user=cwtadm
bamboo_repository_136380561_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa-pkg.git
bamboo_buildKey=CASASPECS-MASTERTESTPY312-TTU2P
bamboo_pypi_server_alias=casa-test-wheel
bamboo_repository_136380561_name=OPEN-CASA-PKG
bamboo_repository_136380560_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_simpleBuildTimeStamp=20260408090938
bamboo_planRepository_2_branchDisplayName=master
bamboo_shortJobName=Test Tools Many Linux 2.28 Ubuntu 24.04 Py3.12
bamboo_repository_136380561_branch_name=master
bamboo_buildResultsUrl=https://open-bamboo.nrao.edu/browse/CASASPECS-MASTERTESTPY312-TTU2P-253
bamboo_repository_136380560_branch_name=master
bamboo_planRepository_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_atlutil_user=casaadm
bamboo_agentId=149651462
bamboo_repository_136380560_name=CASA6
bamboo_rocky8_docker_image=casa-docker.nrao.edu:5000/casa-test-rocky-8.10:4
bamboo_dockerContainerNames=wheel-container-test
bamboo_repository_136380561_revision_number=e5285e68467f01199807a1f1a978aec8a4781be7
bamboo_build_commandline_com_atlassian_bamboo_plugins_bamboo_docker_plugin_task_docker_cli_6=/usr/bin/docker run --volume /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P/wheeldirectory:/wheeldirectory --detach -v /opt/casa/data:/opt/casa/data:ro --name wheel-container-test casa-docker.nrao.edu:5000/casa-test-ubuntu-24.04:2 tail -f /dev/null
bamboo_planRepository_revision=61b4f7fa0783c3d03b597000f81f66ef9b23d37c
bamboo_casaadm_password=********
bamboo_planRepository_previousRevision=2cb32bcb39e5744aa06c93ace2cc9cc089a1de6d
bamboo_repository_branch_name=master
bamboo_planRepository_branch=master
bamboo_capability_system_jdk_JDK_11=/usr/lib/jvm/java-11-openjdk-11.0.23.0.9-2.el7_9.i386
bamboo_planRepository_1_type=bbserver
bamboo_planRepository_branchName=master
bamboo_repository_136380561_previous_revision_number=e5285e68467f01199807a1f1a978aec8a4781be7
bamboo_repository_136380561_git_username=
bamboo_capability_system_jdk_JDK=/usr/lib/jvm/java-11-openjdk-11.0.23.0.9-2.el7_9.i386
bamboo_build_commandline__1000001=/bin/docker rm -f wheel-container-test
bamboo_atlutil_password=********
bamboo_planRepository_1_username=
bamboo_planRepository_type=bbserver
bamboo_ManualBuildTriggerReason_userName=vsuorant
bamboo_git_cache_directory=/export/home/cbt-el7-7/bamboohome/xml-data/build-dir/_git-repositories-cache
bamboo_repository_136380560_previous_revision_number=2cb32bcb39e5744aa06c93ace2cc9cc089a1de6d
bamboo_planRepository_2_branchName=master
bamboo_capability_system_builder_node_Node_js=/usr/bin/node
bamboo_capability_system_builder_ant_Ant=/usr
bamboo_tmp_directory=/export/home/cbt-el7-7/bamboohome/temp
bamboo_planKey=CASASPECS-MASTERTESTPY312
bamboo_planRepository_username=
bamboo_planRepository_1_branchName=master
bamboo_dependency_parent_0=CASASPECS-MASTERBUILDCASALITH-260
bamboo_capability_buildmachinetype=test
bamboo_dependency_parent_total=1
bamboo_resultsUrl=https://open-bamboo.nrao.edu/browse/CASASPECS-MASTERTESTPY312-TTU2P-253
bamboo_capability_system_jdk_JDK_11_0_23__JRE_=/usr/lib/jvm/java-11-openjdk-11.0.23.0.9-2.el7_9.i386
bamboo_build_working_directory=/export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P
bamboo_planRepository_1_name=CASA6
bamboo_shortPlanName=Master Test 3.12
bamboo_capability_hostname=cbt-el7-7.cv.nrao.edu
bamboo_repository_136380560_revision_number=61b4f7fa0783c3d03b597000f81f66ef9b23d37c
bamboo_planRepository_2_username=
bamboo_planRepository_name=CASA6
bamboo_buildNumber=253
bamboo_repository_url=https://casa-pip.nrao.edu:443/repository/casa-test-wheel/
bamboo_ManualBuildTriggerReason_stageName=Default Stage
bamboo_planName=CASASPECS - Master Test 3.12
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_7=/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTU2P-253-ScriptBuildTask-998299341556247835.sh
bamboo_shortPlanKey=MASTERTESTPY312
bamboo_shortJobKey=TTU2P
bamboo_rocky9_docker_image=casa-docker.nrao.edu:5000/casa-test-rocky-9.5:1
bamboo_planRepository_2_name=OPEN-CASA-PKG
bamboo_buildTimeStamp=2026-04-08T09:09:38.830-04:00
bamboo_repository_previous_revision_number=2cb32bcb39e5744aa06c93ace2cc9cc089a1de6d
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_3=/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTU2P-253-ScriptBuildTask-4424259439823311208.sh
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_2=/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTU2P-253-ScriptBuildTask-9682553566877863605.sh
bamboo_repository_git_branch=master
bamboo_buildResultKey=CASASPECS-MASTERTESTPY312-TTU2P-253
bamboo_buildPlanName=CASASPECS - Master Test 3.12 - Test Tools Many Linux 2.28 Ubuntu 24.04 Py3.12
bamboo_planRepository_1_revision=61b4f7fa0783c3d03b597000f81f66ef9b23d37c
bamboo_twine_password=********
bamboo_repository_name=OPEN-CASA-PKG
bamboo_planRepository_2_previousRevision=e5285e68467f01199807a1f1a978aec8a4781be7
bamboo_buildFailed=false
bamboo_capability_system_docker_executable=/usr/bin/docker
bamboo_repository_136380560_git_branch=master
bamboo_agentWorkingDirectory=/export/home/cbt-el7-7/bamboohome/xml-data/build-dir
bamboo_planRepository_2_revision=e5285e68467f01199807a1f1a978aec8a4781be7
bamboo_capability_system_git_executable=/usr/bin/git
bamboo_repository_git_username=
bamboo_planRepository_1_previousRevision=2cb32bcb39e5744aa06c93ace2cc9cc089a1de6d
bamboo_repository_136380561_git_branch=master
bamboo_capability_operating_system=EL7
bamboo_planRepository_branchDisplayName=master
bamboo_shortPlanBranchName=
bamboo_capability_project=CASA
bamboo_planRepository_2_type=bbserver
bamboo_repository_136380560_git_username=
bamboo_capability_casa6docker=true
bamboo_plan_storageTag=plan-135954644
bamboo_planRepository_2_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa-pkg.git
bamboo_repository_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_DependencyTriggerReason_triggeringBuildResultKey=CASASPECS-MASTERBUILDCASALITH-260
bamboo_working_directory=/export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P
bamboo_RerunBuildTriggerReason_noOfRetries=1
bamboo_planRepository_1_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_triggerReason_key=com.atlassian.bamboo.plugin.system.triggerReason:RerunBuildTriggerReason
bamboo_ubuntu_docker_image=casa-docker.nrao.edu:5000/casa-test-ubuntu-24.04:2
08-Apr-2026 09:30:24 Package working directory
08-Apr-2026 09:30:24 tar: work: Cannot stat: No such file or directory
08-Apr-2026 09:30:24 tar: Exiting with failure status due to previous errors
08-Apr-2026 09:30:24 total 77448
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest   160659 Apr  8 13:12 casatestutils-6.7.5.13-py3-none-any.whl
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest 78969046 Apr  8 13:12 casatools-6.7.5.13-cp312-cp312-manylinux_2_28_x86_64.whl
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     4340 Apr  8 13:30 test_tool_agentflagger.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1006 Apr  8 13:30 test_tool_calanalysis.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2656 Apr  8 13:30 test_tool_calibrater.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      687 Apr  8 13:30 test_tool_componentlist.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     7467 Apr  8 13:30 test_tool_coordsys.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest    12935 Apr  8 13:30 test_tool_image.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2220 Apr  8 13:30 test_tool_image_collapse.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1048 Apr  8 13:30 test_tool_image_deviation.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2575 Apr  8 13:30 test_tool_image_fitprofile.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1178 Apr  8 13:30 test_tool_image_pbcor.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      883 Apr  8 13:30 test_tool_image_pv.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1006 Apr  8 13:30 test_tool_image_rebin.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1336 Apr  8 13:30 test_tool_image_regrid.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1641 Apr  8 13:30 test_tool_image_statistics.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      765 Apr  8 13:30 test_tool_image_subimage.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      890 Apr  8 13:30 test_tool_image_transpose.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1966 Apr  8 13:30 test_tool_imagepol.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      839 Apr  8 13:30 test_tool_linearmosaic.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      669 Apr  8 13:30 test_tool_logsink.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      549 Apr  8 13:30 test_tool_measures.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     5224 Apr  8 13:30 test_tool_ms.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      367 Apr  8 13:30 test_tool_ms_createmultims.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2095 Apr  8 13:30 test_tool_ms_statwt.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     8633 Apr  8 13:30 test_tool_msmetadata.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      359 Apr  8 13:30 test_tool_msuvbinner.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1171 Apr  8 13:30 test_tool_quanta.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     3977 Apr  8 13:30 test_tool_regionmanager.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2072 Apr  8 13:30 test_tool_sdm.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     2096 Apr  8 13:30 test_tool_sdm_toms.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest      670 Apr  8 13:30 test_tool_simulator.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1717 Apr  8 13:30 test_tool_synthesisutils.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1462 Apr  8 13:30 test_tool_table.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest     1717 Apr  8 13:30 test_tool_vpmanager.xml
08-Apr-2026 09:30:24 -rw-r--r-- 1 casatest casatest       45 Apr  8 13:30 work-casatools-ubuntu-24.04.tar.gz
08-Apr-2026 09:30:24 Done packaging working directory
08-Apr-2026 09:30:24 Finished task 'Tar work directory' with result: Success
08-Apr-2026 09:30:24 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
08-Apr-2026 09:30:24 Parsing test results under /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P...
08-Apr-2026 09:30:25 Finished task 'Parse tests' with result: Success
08-Apr-2026 09:30:25 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
08-Apr-2026 09:30:25
Beginning to execute external process for build 'CASASPECS - Master Test 3.12 - Test Tools Many Linux 2.28 Ubuntu 24.04 Py3.12 #253 (CASASPECS-MASTERTESTPY312-TTU2P-253)'
... running command line:
/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTU2P-253-ScriptBuildTask-211592534876475600.sh
... in: /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P
bamboo_planRepository_1_branch=master
bamboo_QUOTE="
bamboo_planRepository_2_branch=master
bamboo_planRepository_1_branchDisplayName=master
bamboo_repository_revision_number=61b4f7fa0783c3d03b597000f81f66ef9b23d37c
bamboo_twine_user=cwtadm
bamboo_repository_136380561_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa-pkg.git
bamboo_buildKey=CASASPECS-MASTERTESTPY312-TTU2P
bamboo_pypi_server_alias=casa-test-wheel
bamboo_repository_136380561_name=OPEN-CASA-PKG
bamboo_repository_136380560_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_simpleBuildTimeStamp=20260408090938
bamboo_planRepository_2_branchDisplayName=master
bamboo_shortJobName=Test Tools Many Linux 2.28 Ubuntu 24.04 Py3.12
bamboo_repository_136380561_branch_name=master
bamboo_buildResultsUrl=https://open-bamboo.nrao.edu/browse/CASASPECS-MASTERTESTPY312-TTU2P-253
bamboo_repository_136380560_branch_name=master
bamboo_planRepository_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_atlutil_user=casaadm
bamboo_agentId=149651462
bamboo_repository_136380560_name=CASA6
bamboo_rocky8_docker_image=casa-docker.nrao.edu:5000/casa-test-rocky-8.10:4
bamboo_dockerContainerNames=wheel-container-test
bamboo_repository_136380561_revision_number=e5285e68467f01199807a1f1a978aec8a4781be7
bamboo_build_commandline_com_atlassian_bamboo_plugins_bamboo_docker_plugin_task_docker_cli_6=/usr/bin/docker run --volume /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P/wheeldirectory:/wheeldirectory --detach -v /opt/casa/data:/opt/casa/data:ro --name wheel-container-test casa-docker.nrao.edu:5000/casa-test-ubuntu-24.04:2 tail -f /dev/null
bamboo_planRepository_revision=61b4f7fa0783c3d03b597000f81f66ef9b23d37c
bamboo_casaadm_password=********
bamboo_planRepository_previousRevision=2cb32bcb39e5744aa06c93ace2cc9cc089a1de6d
bamboo_repository_branch_name=master
bamboo_planRepository_branch=master
bamboo_capability_system_jdk_JDK_11=/usr/lib/jvm/java-11-openjdk-11.0.23.0.9-2.el7_9.i386
bamboo_planRepository_1_type=bbserver
bamboo_planRepository_branchName=master
bamboo_repository_136380561_previous_revision_number=e5285e68467f01199807a1f1a978aec8a4781be7
bamboo_repository_136380561_git_username=
bamboo_capability_system_jdk_JDK=/usr/lib/jvm/java-11-openjdk-11.0.23.0.9-2.el7_9.i386
bamboo_build_commandline__1000001=/bin/docker rm -f wheel-container-test
bamboo_atlutil_password=********
bamboo_planRepository_1_username=
bamboo_planRepository_type=bbserver
bamboo_ManualBuildTriggerReason_userName=vsuorant
bamboo_git_cache_directory=/export/home/cbt-el7-7/bamboohome/xml-data/build-dir/_git-repositories-cache
bamboo_repository_136380560_previous_revision_number=2cb32bcb39e5744aa06c93ace2cc9cc089a1de6d
bamboo_planRepository_2_branchName=master
bamboo_capability_system_builder_node_Node_js=/usr/bin/node
bamboo_capability_system_builder_ant_Ant=/usr
bamboo_tmp_directory=/export/home/cbt-el7-7/bamboohome/temp
bamboo_planKey=CASASPECS-MASTERTESTPY312
bamboo_planRepository_username=
bamboo_planRepository_1_branchName=master
bamboo_dependency_parent_0=CASASPECS-MASTERBUILDCASALITH-260
bamboo_capability_buildmachinetype=test
bamboo_dependency_parent_total=1
bamboo_resultsUrl=https://open-bamboo.nrao.edu/browse/CASASPECS-MASTERTESTPY312-TTU2P-253
bamboo_capability_system_jdk_JDK_11_0_23__JRE_=/usr/lib/jvm/java-11-openjdk-11.0.23.0.9-2.el7_9.i386
bamboo_build_working_directory=/export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P
bamboo_planRepository_1_name=CASA6
bamboo_shortPlanName=Master Test 3.12
bamboo_capability_hostname=cbt-el7-7.cv.nrao.edu
bamboo_repository_136380560_revision_number=61b4f7fa0783c3d03b597000f81f66ef9b23d37c
bamboo_planRepository_2_username=
bamboo_planRepository_name=CASA6
bamboo_buildNumber=253
bamboo_repository_url=https://casa-pip.nrao.edu:443/repository/casa-test-wheel/
bamboo_ManualBuildTriggerReason_stageName=Default Stage
bamboo_planName=CASASPECS - Master Test 3.12
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_7=/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTU2P-253-ScriptBuildTask-998299341556247835.sh
bamboo_shortPlanKey=MASTERTESTPY312
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_8=/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTU2P-253-ScriptBuildTask-7328717770067396062.sh
bamboo_shortJobKey=TTU2P
bamboo_rocky9_docker_image=casa-docker.nrao.edu:5000/casa-test-rocky-9.5:1
bamboo_planRepository_2_name=OPEN-CASA-PKG
bamboo_buildTimeStamp=2026-04-08T09:09:38.830-04:00
bamboo_repository_previous_revision_number=2cb32bcb39e5744aa06c93ace2cc9cc089a1de6d
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_3=/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTU2P-253-ScriptBuildTask-4424259439823311208.sh
bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_2=/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTU2P-253-ScriptBuildTask-9682553566877863605.sh
bamboo_repository_git_branch=master
bamboo_buildResultKey=CASASPECS-MASTERTESTPY312-TTU2P-253
bamboo_buildPlanName=CASASPECS - Master Test 3.12 - Test Tools Many Linux 2.28 Ubuntu 24.04 Py3.12
bamboo_planRepository_1_revision=61b4f7fa0783c3d03b597000f81f66ef9b23d37c
bamboo_twine_password=********
bamboo_repository_name=OPEN-CASA-PKG
bamboo_planRepository_2_previousRevision=e5285e68467f01199807a1f1a978aec8a4781be7
bamboo_buildFailed=false
bamboo_capability_system_docker_executable=/usr/bin/docker
bamboo_repository_136380560_git_branch=master
bamboo_agentWorkingDirectory=/export/home/cbt-el7-7/bamboohome/xml-data/build-dir
bamboo_planRepository_2_revision=e5285e68467f01199807a1f1a978aec8a4781be7
bamboo_capability_system_git_executable=/usr/bin/git
bamboo_repository_git_username=
bamboo_planRepository_1_previousRevision=2cb32bcb39e5744aa06c93ace2cc9cc089a1de6d
bamboo_repository_136380561_git_branch=master
bamboo_capability_operating_system=EL7
bamboo_planRepository_branchDisplayName=master
bamboo_shortPlanBranchName=
bamboo_capability_project=CASA
bamboo_planRepository_2_type=bbserver
bamboo_repository_136380560_git_username=
bamboo_capability_casa6docker=true
bamboo_plan_storageTag=plan-135954644
bamboo_planRepository_2_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa-pkg.git
bamboo_repository_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_DependencyTriggerReason_triggeringBuildResultKey=CASASPECS-MASTERBUILDCASALITH-260
bamboo_working_directory=/export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P
bamboo_RerunBuildTriggerReason_noOfRetries=1
bamboo_planRepository_1_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git
bamboo_triggerReason_key=com.atlassian.bamboo.plugin.system.triggerReason:RerunBuildTriggerReason
bamboo_ubuntu_docker_image=casa-docker.nrao.edu:5000/casa-test-ubuntu-24.04:2
08-Apr-2026 09:30:25 Starting cleanup
08-Apr-2026 09:30:35 wheel-container-test
08-Apr-2026 09:30:39 wheel-container-test
08-Apr-2026 09:30:39 Cleanup complete
08-Apr-2026 09:30:39 Finished task 'Cleanup' with result: Success
08-Apr-2026 09:30:39 Running post build plugin 'Docker Container Cleanup'
08-Apr-2026 09:30:39
Beginning to execute external process for build 'CASASPECS - Master Test 3.12 - Test Tools Many Linux 2.28 Ubuntu 24.04 Py3.12 #253 (CASASPECS-MASTERTESTPY312-TTU2P-253)'
... running command line:
/usr/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTU2P
08-Apr-2026 09:30:39 Error response from daemon: No such container: wheel-container-test
08-Apr-2026 09:30:39 Running post build plugin 'NCover Results Collector'
08-Apr-2026 09:30:39 Running post build plugin 'Build Results Label Collector'
08-Apr-2026 09:30:39 Running post build plugin 'Clover Results Collector'
08-Apr-2026 09:30:39 Running post build plugin 'npm Cache Cleanup'
08-Apr-2026 09:30:39 Running post build plugin 'Artifact Copier'
08-Apr-2026 09:30:39 Publishing an artifact: XML Directories
08-Apr-2026 09:30:39 Finished publishing of artifact Non required job artifact Http Compression On : [XML Directories], patterns: [**/*.xml] anchored at: [wheeldirectory/] in 817.8 ms
08-Apr-2026 09:30:39 Finalising the build...
08-Apr-2026 09:30:39 Stopping timer.
08-Apr-2026 09:30:39 Build CASASPECS-MASTERTESTPY312-TTU2P-253 completed.
08-Apr-2026 09:30:40 Running on server: post build plugin 'Build Hanging Detection Configuration'
08-Apr-2026 09:30:40 Running on server: post build plugin 'NCover Results Collector'
08-Apr-2026 09:30:40 Running on server: post build plugin 'Build Labeller'
08-Apr-2026 09:30:40 Running on server: post build plugin 'Clover Delta Calculator'
08-Apr-2026 09:30:40 Running on server: post build plugin 'Maven Dependencies Postprocessor'
08-Apr-2026 09:30:40 All post build plugins have finished
08-Apr-2026 09:30:40 Generating build results summary...
08-Apr-2026 09:30:40 Saving build results to disk...
08-Apr-2026 09:30:40 Store variable context...
08-Apr-2026 09:30:40 Finished building CASASPECS-MASTERTESTPY312-TTU2P-253.

Older logs

Filename Actions
plan-135954644-TTU2P-253.log.1 Download or view