Build: #1 failed

Job: Test tools OSX1015 failed

Stages & jobs

  1. Default Stage

  2. Publish

  3. Create Upload Plan Branch

Build log

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

12-Sep-2022 13:28:32 PASSED
12-Sep-2022 13:28:32
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        name::        No table opened.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        showcache::        The Table Cache is empty.
12-Sep-2022 13:28:32 test_tool_image_pbcor.py::ia_pbcor_test::test_full_image_using_cutoff
12-Sep-2022 13:28:32 ia.pbcor: Test full image divide with cutoff
12-Sep-2022 13:28:32 PASSED
12-Sep-2022 13:28:32
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageFactory::createImage        Created Paged image 'zz.im' of shape [20, 20] with float valued pixels.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        SubImageFactory::createImage        Creating image 'pb_out.im' of shape [20, 20]
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ::::ImageFactory::createImage        Created Paged image 'zz.im' of shape [20, 20] with float valued pixels.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ::::image::fromshape        Ran ia.fromshape
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ::::image::fromshape        ia.fromshape(outfile="zz.im", shape=[20,20], csys={...}, linear=false, overwrite=false, log=true, type="f")
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ::::image::pbcor        Ran ia.pbcor
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ::::image::pbcor        ia.pbcor(pbimage="(...)", outfile="pb_out.im", overwrite=false, box="", region={...}, chans="", stokes="", mask="", mode="divide", cutoff=-1.000000, stretch=false)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        name::        No table opened.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        showcache::        The Table Cache is empty.
12-Sep-2022 13:28:32 test_tool_image_pbcor.py::ia_pbcor_test::test_history
12-Sep-2022 13:28:32 Test history records are written
12-Sep-2022 13:28:32 PASSED
12-Sep-2022 13:28:32
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageFactory::createImage        Created Paged image 'mult_pb.im' of shape [113, 76] with float valued pixels.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 0, 0]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, I)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord        Selected bounding box :
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        name::        No table opened.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        showcache::        The Table Cache is empty.
12-Sep-2022 13:28:32 test_tool_image_pbcor.py::ia_pbcor_test::test_multiply
12-Sep-2022 13:28:32 ia.pbcor: Test full image multiply with cutoff. Primary beam is 2 D, image is 4 D
12-Sep-2022 13:28:32 PASSED
12-Sep-2022 13:28:32
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageFactory::createImage        Created Temp image  of shape [113, 76, 1, 1] with float valued pixels.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        SubImageFactory::createImage        Creating image 'maskim' of shape [113, 76, 1, 1]
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
12-Sep-2022 13:28:32 2022-09-12 17:28:32        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4207)        Exception Reported: The input image shape ([113, 76, 5, 2]) and mask shape ([113, 76, 1, 1]) are different, and it was specified that the mask should not be extended, so the mask cannot be applied to the (sub)image. Specifying that the mask should be extended may resolve the issue at File: src/code/imageanalysis/ImageAnalysis/SubImageFactory.tcc, line: 306
12-Sep-2022 13:28:32 2022-09-12 17:28:32        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4126)        Another image is already open, closing first
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        SubImageFactory::createImage        Creating image 'blahblah' of shape [113, 76, 5, 2]
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        name::        No table opened.
12-Sep-2022 13:28:32 2022-09-12 17:28:32        INFO        showcache::        The Table Cache is empty.
12-Sep-2022 13:28:32 test_tool_image_pbcor.py::ia_pbcor_test::test_stretch
12-Sep-2022 13:28:32 ia.pbcor(): Test stretch parameter
12-Sep-2022 13:28:32 PASSED
12-Sep-2022 13:28:32
12-Sep-2022 13:28:32 - generated xml file: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_image_pbcor/nose.xml -
12-Sep-2022 13:28:32 ============================== 9 passed in 0.92s ===============================
12-Sep-2022 13:28:32 ============================= test session starts ==============================
12-Sep-2022 13:28:32 platform darwin -- Python 3.6.10, pytest-7.0.1, pluggy-1.0.0 -- /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6testenv/bin/python
12-Sep-2022 13:28:32 cachedir: .pytest_cache
12-Sep-2022 13:28:32 rootdir: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater, configfile: pytest.ini
12-Sep-2022 13:28:32 collecting ... collected 24 items
12-Sep-2022 13:28:32
12-Sep-2022 13:28:32
12-Sep-2022 13:28:33 2022-09-12 17:28:33        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:28:33 2022-09-12 17:28:33        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:28:33 2022-09-12 17:28:33        INFO                Clearing all model records in MS header.
12-Sep-2022 13:28:33 2022-09-12 17:28:33        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:28:34 2022-09-12 17:28:35        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:28:35 2022-09-12 17:28:35        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:28:35 2022-09-12 17:28:35        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:28:35 2022-09-12 17:28:35        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:28:35 2022-09-12 17:28:35        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:28:35 2022-09-12 17:28:35        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:28:35 2022-09-12 17:28:35        INFO        calibrater::corrupt        Beginning corrupt---------------------------
12-Sep-2022 13:28:35 2022-09-12 17:28:35        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:28:35 2022-09-12 17:28:35        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:28:37 2022-09-12 17:28:37        INFO        calibrater::corrupt        Finished corrupting.
12-Sep-2022 13:28:37 2022-09-12 17:28:37        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:28:37 test_tool_calibrater.py::calibrater_test::test_activityRecord
12-Sep-2022 13:28:37 Check that using the calibrater to modify the vis shows in the activity record 
12-Sep-2022 13:28:37 PASSED
12-Sep-2022 13:28:37
12-Sep-2022 13:28:37 2022-09-12 17:28:37        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:28:37 2022-09-12 17:28:37        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:28:37 2022-09-12 17:28:37        INFO                Clearing all model records in MS header.
12-Sep-2022 13:28:37 2022-09-12 17:28:37        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:28:38 2022-09-12 17:28:39        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:28:39 2022-09-12 17:28:39        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:28:39 2022-09-12 17:28:39        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:28:39 2022-09-12 17:28:39        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:28:39 2022-09-12 17:28:39        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:28:39 2022-09-12 17:28:39        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:28:39 2022-09-12 17:28:39        INFO        calibrater::corrupt        Beginning corrupt---------------------------
12-Sep-2022 13:28:39 2022-09-12 17:28:39        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:28:39 2022-09-12 17:28:39        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:28:40 2022-09-12 17:28:41        INFO        calibrater::corrupt        Finished corrupting.
12-Sep-2022 13:28:41 2022-09-12 17:28:42        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:28:41 test_tool_calibrater.py::calibrater_test::test_corruptCal
12-Sep-2022 13:28:41 Check that the MS is corrupted using the cal table 
12-Sep-2022 13:28:41 PASSED
12-Sep-2022 13:28:41
12-Sep-2022 13:28:42 2022-09-12 17:28:42        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:28:42 2022-09-12 17:28:42        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:28:42 2022-09-12 17:28:42        INFO                Clearing all model records in MS header.
12-Sep-2022 13:28:42 2022-09-12 17:28:42        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:28:43 2022-09-12 17:28:44        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:28:43 2022-09-12 17:28:44        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:28:43 2022-09-12 17:28:44        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:28:43 2022-09-12 17:28:44        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:28:43 test_tool_calibrater.py::calibrater_test::test_createEmpty
12-Sep-2022 13:28:43 Check that an empty cal table can be created
12-Sep-2022 13:28:43 PASSED
12-Sep-2022 13:28:43
12-Sep-2022 13:28:44 2022-09-12 17:28:44        INFO        name::        No table opened.
12-Sep-2022 13:28:44 2022-09-12 17:28:44        INFO        showcache::        The Table Cache is empty.
12-Sep-2022 13:28:44 2022-09-12 17:28:44        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:28:44 2022-09-12 17:28:44        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:28:44 2022-09-12 17:28:44        INFO                Clearing all model records in MS header.
12-Sep-2022 13:28:44 2022-09-12 17:28:44        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        name::        No table opened.
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::        The Table Cache has the following 16 entries:
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            0: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            1: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            2: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            3: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            4: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            5: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            6: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            7: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            8: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            9: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            10: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            11: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            12: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            13: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            14: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        INFO        showcache::            15: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
12-Sep-2022 13:28:45 2022-09-12 17:28:46        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:28:45 test_tool_calibrater.py::calibrater_test::test_done
12-Sep-2022 13:28:45 Check that done closes the active calibrator tool 
12-Sep-2022 13:28:45 PASSED
12-Sep-2022 13:28:45
12-Sep-2022 13:28:46 2022-09-12 17:28:46        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
12-Sep-2022 13:28:46 2022-09-12 17:28:46        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
12-Sep-2022 13:28:46 2022-09-12 17:28:46        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:28:46 2022-09-12 17:28:46        INFO                Clearing all model records in MS header.
12-Sep-2022 13:28:46 2022-09-12 17:28:46        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:28:47 2022-09-12 17:28:48        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:28:47 2022-09-12 17:28:48        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:28:47 2022-09-12 17:28:48        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:28:47 2022-09-12 17:28:48        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:28:47 2022-09-12 17:28:48        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:28:47 2022-09-12 17:28:48        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:28:47 2022-09-12 17:28:48        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:28:47 2022-09-12 17:28:48        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:28:47 2022-09-12 17:28:48        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:28:50 2022-09-12 17:28:50        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:28:50 2022-09-12 17:28:50        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
12-Sep-2022 13:28:50 2022-09-12 17:28:50        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:28:50 2022-09-12 17:28:50        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:28:50 2022-09-12 17:28:51        INFO        calibrater::initcalset        Beginning initcalset------------------------
12-Sep-2022 13:28:50 2022-09-12 17:28:51        INFO        VisSet::VisSet        Initializing MODEL_DATA (to unity) and CORRECTED_DATA (to DATA)
12-Sep-2022 13:28:50 2022-09-12 17:28:51        INFO        VisSet::VisSet        Initialized 328860 rows.
12-Sep-2022 13:28:51 2022-09-12 17:28:52        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
12-Sep-2022 13:28:51 test_tool_calibrater.py::calibrater_test::test_reinitModel
12-Sep-2022 13:28:51 Check that initcalset will reset the CORRECTED_DATA to unity 
12-Sep-2022 13:28:51 PASSED
12-Sep-2022 13:28:51
12-Sep-2022 13:28:51 2022-09-12 17:28:52        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:28:51 2022-09-12 17:28:52        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:28:51 2022-09-12 17:28:52        INFO                Clearing all model records in MS header.
12-Sep-2022 13:28:51 2022-09-12 17:28:52        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:28:53 2022-09-12 17:28:53        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        Calibrater::setsolve        Arranging to SOLVE:
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        Calibrater::setsolve        .   Mf Mueller: table=gaincaltest2.ms.G0 append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        calibrater::solve        Beginning solve-----------------------------
12-Sep-2022 13:28:53 2022-09-12 17:28:54        SEVERE        Calibrater::solve        Caught exception: Please run setsolve before attempting to solve.
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        Calibrater::solve        Reseting entire solve/apply state.
12-Sep-2022 13:28:53 2022-09-12 17:28:54        SEVERE                Exception Reported: Error in Calibrater::solve.
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        Calibrater::correct2 (VI2/VB2)        .   (None)
12-Sep-2022 13:28:53 2022-09-12 17:28:54        SEVERE        Calibrater::correct2 (VI2/VB2)        Caught exception: Nothing to Apply
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        Calibrater::correct2 (VI2/VB2)        Resetting all calibration application settings.
12-Sep-2022 13:28:53 2022-09-12 17:28:54        SEVERE                Exception Reported: Error in Calibrater::correct.
12-Sep-2022 13:28:53 2022-09-12 17:28:54        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:28:53 test_tool_calibrater.py::calibrater_test::test_resetSolveApply
12-Sep-2022 13:28:53 Check that the reset function can clear set apply and solves 
12-Sep-2022 13:28:53 PASSED
12-Sep-2022 13:28:53
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO                Clearing all model records in MS header.
12-Sep-2022 13:28:53 2022-09-12 17:28:54        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:28:55 2022-09-12 17:28:55        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:28:55 2022-09-12 17:28:56        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:28:55 2022-09-12 17:28:56        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:28:56 2022-09-12 17:28:56        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
12-Sep-2022 13:28:56 2022-09-12 17:28:56        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:28:56 2022-09-12 17:28:56        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
12-Sep-2022 13:28:56 2022-09-12 17:28:56        INFO        Calibrater::selectvis+         Selecting on spw: '1'
12-Sep-2022 13:28:56 2022-09-12 17:28:56        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 82215
12-Sep-2022 13:28:56 2022-09-12 17:28:56        INFO        Calibrater::selectvis        Frequency selection:
12-Sep-2022 13:28:56 2022-09-12 17:28:56        INFO        Calibrater::selectvis        .  Spw 1:0~7 (8 channels, step by 1)
12-Sep-2022 13:28:56 2022-09-12 17:28:56        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
12-Sep-2022 13:28:56 2022-09-12 17:28:56        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:28:56 test_tool_calibrater.py::calibrater_test::test_returnDict
12-Sep-2022 13:28:56 Check that the returndict function gives a dictonary with the expected keys 
12-Sep-2022 13:28:56 PASSED
12-Sep-2022 13:28:56
12-Sep-2022 13:28:57 2022-09-12 17:28:57        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:28:57 2022-09-12 17:28:57        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:28:57 2022-09-12 17:28:57        INFO                Clearing all model records in MS header.
12-Sep-2022 13:28:57 2022-09-12 17:28:57        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        Calibrater::selectvis+         Selecting on antenna/baseline: '0'
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 65772
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:28:58 2022-09-12 17:28:59        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:00 2022-09-12 17:29:00        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:29:00 2022-09-12 17:29:01        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:00 test_tool_calibrater.py::calibrater_test::test_selectVisBaseline
12-Sep-2022 13:29:00 Check that selectvis properly selects baseline/antenna
12-Sep-2022 13:29:00 65772 65772
12-Sep-2022 13:29:00 263088 263088
12-Sep-2022 13:29:00 PASSED
12-Sep-2022 13:29:00
12-Sep-2022 13:29:01 2022-09-12 17:29:02        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:01 2022-09-12 17:29:02        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:01 2022-09-12 17:29:02        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:01 2022-09-12 17:29:02        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:02 2022-09-12 17:29:03        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        Calibrater::selectvis+         Selecting on field: '0'
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 172260
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:03 2022-09-12 17:29:03        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:04 2022-09-12 17:29:04        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:29:04 2022-09-12 17:29:05        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:04 test_tool_calibrater.py::calibrater_test::test_selectVisField
12-Sep-2022 13:29:04 Check that the field is properly selected by selectvis
12-Sep-2022 13:29:04 PASSED
12-Sep-2022 13:29:04
12-Sep-2022 13:29:05 2022-09-12 17:29:06        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:05 2022-09-12 17:29:06        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:05 2022-09-12 17:29:06        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:05 2022-09-12 17:29:06        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        Calibrater::selectvis+         Selecting on intent: '*AMPLI*'
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        Calibrater::selectvis        Selection did not drop any rows
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:07 2022-09-12 17:29:07        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:09 2022-09-12 17:29:09        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:29:09 2022-09-12 17:29:10        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:09 test_tool_calibrater.py::calibrater_test::test_selectVisIntent
12-Sep-2022 13:29:09 Check that the intent is properly selected by selectvis
12-Sep-2022 13:29:09 PASSED
12-Sep-2022 13:29:09
12-Sep-2022 13:29:10 2022-09-12 17:29:10        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:10 2022-09-12 17:29:10        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:10 2022-09-12 17:29:10        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:10 2022-09-12 17:29:10        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        Calibrater::selectvis+         Selecting by observation IDs: '0'
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 318870
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:11 2022-09-12 17:29:12        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:14 2022-09-12 17:29:14        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:29:14 2022-09-12 17:29:14        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:14 test_tool_calibrater.py::calibrater_test::test_selectVisObs
12-Sep-2022 13:29:14 PASSED
12-Sep-2022 13:29:14
12-Sep-2022 13:29:14 2022-09-12 17:29:15        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:14 2022-09-12 17:29:15        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:14 2022-09-12 17:29:15        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:14 2022-09-12 17:29:15        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:16 2022-09-12 17:29:16        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        Calibrater::selectvis+         Selecting on scan: '2'
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 15660
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:16 2022-09-12 17:29:17        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:29:17 2022-09-12 17:29:17        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:17 test_tool_calibrater.py::calibrater_test::test_selectVisScan
12-Sep-2022 13:29:17 Check tht the scan is properly selected by selectvis
12-Sep-2022 13:29:17 PASSED
12-Sep-2022 13:29:17
12-Sep-2022 13:29:17 2022-09-12 17:29:18        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:17 2022-09-12 17:29:18        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:17 2022-09-12 17:29:18        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:17 2022-09-12 17:29:18        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        Calibrater::selectvis+         Selecting on spw: '0'
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 82215
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        Calibrater::selectvis        Frequency selection:
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        Calibrater::selectvis        .  Spw 0:0~7 (8 channels, step by 1)
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:29:19 2022-09-12 17:29:19        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:29:19 2022-09-12 17:29:20        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:19 2022-09-12 17:29:20        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:29:19 2022-09-12 17:29:20        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:19 2022-09-12 17:29:20        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:20 2022-09-12 17:29:20        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:29:20 2022-09-12 17:29:21        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:20 test_tool_calibrater.py::calibrater_test::test_selectVisSpw
12-Sep-2022 13:29:20 Check that spw is properly selected by selectvis
12-Sep-2022 13:29:20 PASSED
12-Sep-2022 13:29:20
12-Sep-2022 13:29:21 2022-09-12 17:29:21        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:21 2022-09-12 17:29:21        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:21 2022-09-12 17:29:21        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:21 2022-09-12 17:29:21        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        Calibrater::selectvis+         Selecting on time: '>04:38:23'
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 313200
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:22 2022-09-12 17:29:23        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:24 2022-09-12 17:29:25        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:29:25 2022-09-12 17:29:25        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:25 test_tool_calibrater.py::calibrater_test::test_selectVisTime
12-Sep-2022 13:29:25 Check that time is properly selected by selectvis
12-Sep-2022 13:29:25 PASSED
12-Sep-2022 13:29:25
12-Sep-2022 13:29:25 2022-09-12 17:29:26        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:25 2022-09-12 17:29:26        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:25 2022-09-12 17:29:26        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:25 2022-09-12 17:29:26        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:27 2022-09-12 17:29:27        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:27 2022-09-12 17:29:27        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:27 2022-09-12 17:29:27        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:27 2022-09-12 17:29:27        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
12-Sep-2022 13:29:27 2022-09-12 17:29:27        INFO        calibrater::reset        Reseting solve/apply state
12-Sep-2022 13:29:27 2022-09-12 17:29:27        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
12-Sep-2022 13:29:27 2022-09-12 17:29:27        INFO        Calibrater::selectvis+         Selecting on uvrange: '> 500000lambda'
12-Sep-2022 13:29:27 2022-09-12 17:29:28        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 103321
12-Sep-2022 13:29:27 2022-09-12 17:29:28        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
12-Sep-2022 13:29:27 2022-09-12 17:29:28        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
12-Sep-2022 13:29:27 2022-09-12 17:29:28        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:29:27 2022-09-12 17:29:28        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:29:27 2022-09-12 17:29:28        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:27 2022-09-12 17:29:28        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:29:27 2022-09-12 17:29:28        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:27 2022-09-12 17:29:28        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:29 2022-09-12 17:29:29        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:29:29 2022-09-12 17:29:30        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:29 test_tool_calibrater.py::calibrater_test::test_selectVisUVrange
12-Sep-2022 13:29:29 Check that selectvis properly selects for uvrange
12-Sep-2022 13:29:29 PASSED
12-Sep-2022 13:29:29
12-Sep-2022 13:29:30 2022-09-12 17:29:30        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:30 2022-09-12 17:29:30        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:30 2022-09-12 17:29:30        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:30 2022-09-12 17:29:30        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        calibrater::parsecallibfile        Beginning parsecallibfile-)-------
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        calibrater::setcallib        Beginning setcallib---------
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        Calibrater::setcallib2(callib)        Arranging to APPLY:
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO                .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO                .   0:
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        +             MS: obs= fld= intent= spw=
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        +             CT: tinterp=linear finterp=
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        +                 obsmap=[]         fldmap=[]
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        +                 spwmap=[]         antmap=[]
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        calibrater::corrupt        Beginning corrupt---------------------------
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:31 2022-09-12 17:29:32        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
12-Sep-2022 13:29:33 2022-09-12 17:29:34        INFO        calibrater::corrupt        Finished corrupting.
12-Sep-2022 13:29:33 2022-09-12 17:29:34        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:34 test_tool_calibrater.py::calibrater_test::test_setCalLib
12-Sep-2022 13:29:34 Check that a provided cal table can be used to corrupt the MODEL_DATA 
12-Sep-2022 13:29:34 PASSED
12-Sep-2022 13:29:34
12-Sep-2022 13:29:34 2022-09-12 17:29:34        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:34 2022-09-12 17:29:34        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:34 2022-09-12 17:29:34        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:34 2022-09-12 17:29:34        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:35 2022-09-12 17:29:36        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:35 2022-09-12 17:29:36        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:35 2022-09-12 17:29:36        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:35 2022-09-12 17:29:36        INFO        Calibrater::setCorrDepFlags        Setting correlation dependent flags = True
12-Sep-2022 13:29:35 2022-09-12 17:29:36        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:35 test_tool_calibrater.py::calibrater_test::test_setCorrDepFlags
12-Sep-2022 13:29:35 Check that corrdepflags will be checked 
12-Sep-2022 13:29:35 PASSED
12-Sep-2022 13:29:35
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO        calibrater::smooth        Beginning smooth--(MSSelection version)-------
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO        Calibrater::smooth        Beginning smoothing/interpolating method.
12-Sep-2022 13:29:36 2022-09-12 17:29:36        WARN                Found pre-v4.1 caltable (/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/ngc5921.gcal); attempting to update...
12-Sep-2022 13:29:36 2022-09-12 17:29:36        WARN        +        SUCCEEDED: trivial OBSERVATION/OBSERVATION_ID have been added.
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO        Calibrater::smooth        Storing result in testcalout.cal
12-Sep-2022 13:29:36 2022-09-12 17:29:36        INFO                Writing solutions to table: testcalout.cal
12-Sep-2022 13:29:36 2022-09-12 17:29:37        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:36 test_tool_calibrater.py::calibrater_test::test_smoothedCalTables
12-Sep-2022 13:29:36 Check that the smooth command creates a smoothed cal table 
12-Sep-2022 13:29:36 PASSED
12-Sep-2022 13:29:36
12-Sep-2022 13:29:36 2022-09-12 17:29:37        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
12-Sep-2022 13:29:36 2022-09-12 17:29:37        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
12-Sep-2022 13:29:36 2022-09-12 17:29:37        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
12-Sep-2022 13:29:36 2022-09-12 17:29:37        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:36 2022-09-12 17:29:37        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:36 2022-09-12 17:29:37        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        calibrater::setsolvebandpoly        Beginning setsolvebandpoly--(MSSelection version)-------
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        Calibrater::setsolvebandpoly        Arranging to SOLVE:
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        Calibrater::setsolvebandpoly        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        calibrater::solve        Beginning solve-----------------------------
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        Calibrater::solve        .   (None)
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        Calibrater::solve        The following calibration term is arranged for solve:
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        Calibrater::solve        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        Calibrater::solve        Solving for BPOLY
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO                For solint = inf, found 7 solution intervals.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.059507/0.0681762/0.0765806
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.33653/0.750193/1.6779
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0630839/0.0689061/0.0756384
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.279482/0.74979/1.59355
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0672607/0.089081/0.111106
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.31075/3.32219/4.29305
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0652822/0.0889198/0.116912
12-Sep-2022 13:29:37 2022-09-12 17:29:37        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.44269/3.26616/4.3519
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350681/0.587724/0.894558
12-Sep-2022 13:29:37 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:38 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.296/59.0077/111.454
12-Sep-2022 13:29:38 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:38 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.354236/0.581733/0.988903
12-Sep-2022 13:29:38 2022-09-12 17:29:38        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 17.2419/56.3249/117.264
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0850169/0.113968/0.15276
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.67561/5.33243/8.17296
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0784237/0.114345/0.159908
12-Sep-2022 13:29:39 2022-09-12 17:29:39        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.46392/5.27232/7.20673
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0703936/0.102501/0.131676
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.98728/4.47977/5.84264
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0751114/0.102994/0.138529
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.18629/4.47068/5.89411
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350626/0.604424/0.974388
12-Sep-2022 13:29:39 2022-09-12 17:29:40        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:40 2022-09-12 17:29:41        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.2542/59.3511/120.719
12-Sep-2022 13:29:40 2022-09-12 17:29:41        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:40 2022-09-12 17:29:41        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.382806/0.598119/0.941571
12-Sep-2022 13:29:40 2022-09-12 17:29:41        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 20.0637/57.2112/118.101
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0750931/0.0978998/0.125897
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.72933/3.98787/5.33715
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0658572/0.0972125/0.140794
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.97943/3.97841/5.72903
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        Calibrater::solve          Found good BPOLY solutions in 7 slots.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        INFO        calibrater::solve        Finished solving.
12-Sep-2022 13:29:41 2022-09-12 17:29:42        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
12-Sep-2022 13:29:41 test_tool_calibrater.py::calibrater_test::test_solveBandpass
12-Sep-2022 13:29:41 Check that solve band poly creates the output table
12-Sep-2022 13:29:41 PASSED
12-Sep-2022 13:29:41
12-Sep-2022 13:29:42 2022-09-12 17:29:42        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        calibrater::setsolvegainspline        Beginning setsolvegainspline--(MSSelection version)-------
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        Calibrater::setsolvegainspline        Arranging to SOLVE:
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        Calibrater::setsolvegainspline        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        calibrater::solve        Beginning solve-----------------------------
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        Calibrater::solve        .   (None)
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        Calibrater::solve        The following calibration term is arranged for solve:
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        Calibrater::solve        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        GJonesSpline::solve()         Fitting time-dependent cubic splines.
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        GJonesSpline::solve()         Solving for phase splines with splinetime= 10800
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        GJonesSpline::solve()         Solving for amplitude splines with splinetime= 10800
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1331+30500002_0, spw= 0, nchan= 63
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1445+09900002_0, spw= 0, nchan= 63
12-Sep-2022 13:29:42 2022-09-12 17:29:42        INFO        GJonesSpline::solve()         Accumulating data for:  field= N5921_2, spw= 0, nchan= 63
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        GJonesSpline::solve()         Number of timestamps in data = 60
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        GJonesSpline::getKnots()         Gridded splinetime = 5280 sec.
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        GJonesSpline::solve()         Number of cubic spline control points = 4
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        GJonesSpline::solve()         Number of cubic spline knots = 8
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        GJonesSpline::solve()         Number of cubic spline segments = 1
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        GJonesSpline::solve()         Fitting amplitude spline.
12-Sep-2022 13:29:42 test_tool_calibrater.py::calibrater_test::test_solveGainspline  DPBTRF ;problem leading minor           23
12-Sep-2022 13:29:42 DPBTRF ;problem leading minor           23
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        GJonesSpline::solve()         Searching for and correcting phase-wraps on each baseline.
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        GJonesSpline::solve()         Fitting phase spline.
12-Sep-2022 13:29:42 DPBTRF ;problem leading minor           22
12-Sep-2022 13:29:42 DPBTRF ;problem leading minor           22
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        GJonesSpline::updateCalTable         Storing solutions in table gainspline
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        calibrater::solve        Finished solving.
12-Sep-2022 13:29:42 2022-09-12 17:29:43        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
12-Sep-2022 13:29:42
12-Sep-2022 13:29:42 Check that solve gain spline creates the output table 
12-Sep-2022 13:29:42 PASSED
12-Sep-2022 13:29:42
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:42 2022-09-12 17:29:43        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:44 2022-09-12 17:29:44        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO        calibrater::specifycal        Beginning specifycal-----------------------
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO                Creating G Jones table from specified parameters.
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO                Generating 'G' corrections.
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO                Specified parameter(s) (per spw and pol) repeated on all antennas.
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO                spwId=1 antId=<all> polId=<all> parameter= 3   (ip0,ip1 = [0, 0, 0],[1, 0, 9])
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO                Writing solutions to table: testcalout.cal
12-Sep-2022 13:29:44 2022-09-12 17:29:45        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:44 test_tool_calibrater.py::calibrater_test::test_specifyCal
12-Sep-2022 13:29:44 Check that specifycal can set values for specific spws and antennas
12-Sep-2022 13:29:44 PASSED
12-Sep-2022 13:29:44
12-Sep-2022 13:29:44 2022-09-12 17:29:45        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:44 2022-09-12 17:29:45        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:46 2022-09-12 17:29:46        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::setsolve        Arranging to SOLVE:
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::setsolve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::state        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::state        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::state        The following calibration term is arranged for solve:
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::state        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        calibrater::solve        Beginning solve-----------------------------
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::solve        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::solve        The following calibration term is arranged for solve:
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO        Calibrater::solve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO                Solving for Mf Mueller
12-Sep-2022 13:29:46 2022-09-12 17:29:47        INFO                For solint = inf, found 84 solution intervals.
12-Sep-2022 13:29:47 2022-09-12 17:29:48        INFO                  Found good Mf Mueller solutions in 84 intervals.
12-Sep-2022 13:29:47 2022-09-12 17:29:48        INFO                Writing solutions to table: output.ms
12-Sep-2022 13:29:47 2022-09-12 17:29:48        INFO        calibrater::solve        Finished solving.
12-Sep-2022 13:29:48 2022-09-12 17:29:48        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
12-Sep-2022 13:29:48 test_tool_calibrater.py::calibrater_test::test_standardPath
12-Sep-2022 13:29:48 open setapply setsolve state solve close 
12-Sep-2022 13:29:48 PASSED
12-Sep-2022 13:29:48
12-Sep-2022 13:29:48 2022-09-12 17:29:48        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:48 2022-09-12 17:29:48        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:48 2022-09-12 17:29:48        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:48 2022-09-12 17:29:48        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        name::        No table opened.
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::        The Table Cache has the following 16 entries:
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            0: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            1: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            2: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            3: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            4: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            5: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            6: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            7: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            8: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            9: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            10: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            11: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            12: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            13: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            14: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
12-Sep-2022 13:29:49 2022-09-12 17:29:50        INFO        showcache::            15: "/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
12-Sep-2022 13:29:50 2022-09-12 17:29:50        INFO        name::        No table opened.
12-Sep-2022 13:29:50 2022-09-12 17:29:50        INFO        showcache::        The Table Cache is empty.
12-Sep-2022 13:29:50 2022-09-12 17:29:50        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:50 test_tool_calibrater.py::calibrater_test::test_takesMs
12-Sep-2022 13:29:50 Check that the calibrater tool can open and close an MS 
12-Sep-2022 13:29:50 PASSED
12-Sep-2022 13:29:50
12-Sep-2022 13:29:50 2022-09-12 17:29:50        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
12-Sep-2022 13:29:50 2022-09-12 17:29:50        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
12-Sep-2022 13:29:50 2022-09-12 17:29:50        INFO                Clearing all model records in MS header.
12-Sep-2022 13:29:50 2022-09-12 17:29:50        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
12-Sep-2022 13:29:51 2022-09-12 17:29:52        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
12-Sep-2022 13:29:51 2022-09-12 17:29:52        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
12-Sep-2022 13:29:51 2022-09-12 17:29:52        INFO        Calibrater::        Initializing nominal selection to the whole MS.
12-Sep-2022 13:29:51 2022-09-12 17:29:52        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
12-Sep-2022 13:29:51 2022-09-12 17:29:52        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
12-Sep-2022 13:29:51 2022-09-12 17:29:52        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:51 2022-09-12 17:29:52        INFO        calibrater::correct        Beginning correct---------------------------
12-Sep-2022 13:29:51 2022-09-12 17:29:52        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
12-Sep-2022 13:29:51 2022-09-12 17:29:52        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
12-Sep-2022 13:29:54 2022-09-12 17:29:54        INFO        calibrater::correct        Finished correcting.
12-Sep-2022 13:29:54 2022-09-12 17:29:54        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
12-Sep-2022 13:29:54 test_tool_calibrater.py::calibrater_test::test_writeToCorrected
12-Sep-2022 13:29:54 Check that the tool writes to the CORRECTED_DATA column 
12-Sep-2022 13:29:54 PASSED
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 - generated xml file: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml -
12-Sep-2022 13:29:54 ======================== 24 passed in 81.54s (0:01:21) =========================
12-Sep-2022 13:29:54 ============================= test session starts ==============================
12-Sep-2022 13:29:54 platform darwin -- Python 3.6.10, pytest-7.0.1, pluggy-1.0.0 -- /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6testenv/bin/python
12-Sep-2022 13:29:54 cachedir: .pytest_cache
12-Sep-2022 13:29:54 rootdir: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_componentlist, configfile: pytest.ini
12-Sep-2022 13:29:54 collecting ... collected 3 items
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 test_tool_componentlist.py::componentlist_test::test_getfluxerror
12-Sep-2022 13:29:54 Test cl.getfluxerror()
12-Sep-2022 13:29:54 PASSED
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 2022-09-12 17:29:55        WARN        componentlist::close        componentlist closed
12-Sep-2022 13:29:54 2022-09-12 17:29:55        WARN        componentlist::close        componentlist closed
12-Sep-2022 13:29:54 test_tool_componentlist.py::componentlist_test::test_plp
12-Sep-2022 13:29:54 Test adding/updating plp spectral component
12-Sep-2022 13:29:54 PASSED
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize        SUMMARY OF COMPONENT
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+        Shape: Gaussian
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+        Flux density: [(1,0), (0,0), (0,0), (0,0)] Jy +/- [(0,0), (0,0), (0,0), (0,0)]
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+        Spectral model: Constant
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+        Position: Position ---
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+               --- long:   10:30:00.000 (fixed)
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+               --- lat:  - 20.00.00.00 (fixed)
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+        
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+        Size:
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+               --- major axis FWHM:     4.00 arcsec (fixed)
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+               --- minor axis FWHM:     2.00 arcsec (fixed)
12-Sep-2022 13:29:54 2022-09-12 17:29:55        INFO        componentlist::summarize+               --- position angle: 30.0deg (fixed)
12-Sep-2022 13:29:54 2022-09-12 17:29:55        SEVERE        componentlist::summarize        Exception Reported: List has only 1 components, but zero-based component 1 specified. Please specify a component less than 1
12-Sep-2022 13:29:54 test_tool_componentlist.py::componentlist_test::test_summarize
12-Sep-2022 13:29:54 Test the cl.summarize() method
12-Sep-2022 13:29:54 PASSED
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 - generated xml file: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_componentlist/nose.xml -
12-Sep-2022 13:29:54 ============================== 3 passed in 0.27s ===============================
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Traceback (most recent call last):
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54   File "runtest.py", line 603, in run
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_deviation/
12-Sep-2022 13:29:54     shutil.copy2("{}{}.py".format(workdir + "tests/",test), workdir + "{}/".format(test if not test.endswith(".py") else test[:-3]))
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_image_deviation /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_deviation/
12-Sep-2022 13:29:54   File "/opt/casa/03/Library/Frameworks/Python.framework/Versions/3.6/lib/python3.6/shutil.py", line 263, in copy2
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_deviation
12-Sep-2022 13:29:54     copyfile(src, dst, follow_symlinks=follow_symlinks)
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54   File "/opt/casa/03/Library/Frameworks/Python.framework/Versions/3.6/lib/python3.6/shutil.py", line 120, in copyfile
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_image_deviation/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_deviation/']
12-Sep-2022 13:29:54     with open(src, 'rb') as fsrc:
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 FileNotFoundError: [Errno 2] No such file or directory: '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/test_tool_image_fromcomplist.py'
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_ms_createmultims/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_ms_createmultims /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_ms_createmultims/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_ms_createmultims
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_ms_createmultims/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_ms_createmultims/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calanalysis/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_calanalysis /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calanalysis/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calanalysis
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_calanalysis/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calanalysis/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_rebin/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_image_rebin /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_rebin/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_rebin
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_image_rebin/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_rebin/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_measures/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_measures /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_measures/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_measures
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_measures/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_measures/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_fromcomplist/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_image_fromcomplist /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_fromcomplist/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_fromcomplist
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_image_fromcomplist/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_fromcomplist/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_transpose/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_image_transpose /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_transpose/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_transpose
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_image_transpose/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_transpose/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_sdm_toms/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_sdm_toms /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_sdm_toms/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_sdm_toms
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_sdm_toms/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_sdm_toms/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_regionmanager/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_regionmanager /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_regionmanager/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_regionmanager
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_regionmanager/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_regionmanager/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_regrid/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_image_regrid /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_regrid/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_regrid
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_image_regrid/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_regrid/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_ms_statwt/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_ms_statwt /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_ms_statwt/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_ms_statwt
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_ms_statwt/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_ms_statwt/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_image /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_image/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_table/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_table /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_table/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_table
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_table/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_table/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_pbcor/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_image_pbcor /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_pbcor/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_pbcor
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_image_pbcor/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_image_pbcor/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_calibrater /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_calibrater/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Setting Working Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_componentlist/
12-Sep-2022 13:29:54 Copying: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/tests/ to test_tool_componentlist /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_componentlist/
12-Sep-2022 13:29:54 Test Directory: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_componentlist
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Running Command: pytest ['--junitxml=/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/xml/test_tool_componentlist/nose.xml', '-s', '--disable-pytest-warnings', '--verbose', '-ra', '--tb=short', '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15/casa6/casatestutils/nosedir/test_tool_componentlist/']
12-Sep-2022 13:29:54
12-Sep-2022 13:29:54 Finished task 'Test wheel' with result: Success
12-Sep-2022 13:29:54 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
12-Sep-2022 13:29:54 Parsing test results under /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15...
12-Sep-2022 13:29:55 Failing task since 25 failing test cases were found.
12-Sep-2022 13:29:55 Finished task 'Parse tests' with result: Failed
12-Sep-2022 13:29:55 Starting task 'Reset config.py' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
12-Sep-2022 13:29:55
Beginning to execute external process for build 'CASA - Casa 6 Dev Test Full - CAS-13825 - Test tools OSX1015 #1 (CASA-C6DTF220-TTO15-1)'
... running command line:
/Users/casaci/bamboohome/temp/CASA-C6DTF220-TTO15-1-ScriptBuildTask-4983249947823809212.sh
... in: /Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF220-TTO15
12-Sep-2022 13:29:55   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
12-Sep-2022 13:29:55                                  Dload  Upload   Total   Spent    Left  Speed
12-Sep-2022 13:29:55
12-Sep-2022 13:29:55   0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
12-Sep-2022 13:29:55 100    70  100    70    0     0   2258      0 --:--:-- --:--:-- --:--:--  2258
12-Sep-2022 13:29:55 Finished task 'Reset config.py' with result: Success
12-Sep-2022 13:29:55 Running post build plugin 'Docker Container Cleanup'
12-Sep-2022 13:29:55 Running post build plugin 'NCover Results Collector'
12-Sep-2022 13:29:55 Running post build plugin 'Clover Results Collector'
12-Sep-2022 13:29:55 Running post build plugin 'npm Cache Cleanup'
12-Sep-2022 13:29:55 Running post build plugin 'Artifact Copier'
12-Sep-2022 13:29:55 Publishing an artifact: OSX10.15 casatools wheel
12-Sep-2022 13:29:59 Finished publishing of artifact Required shared artifact: [OSX10.15 casatools wheel], pattern: [casatools*.whl] anchored at: [wheeldirectory/] in 4.370 s
12-Sep-2022 13:29:59 Finalising the build...
12-Sep-2022 13:29:59 Stopping timer.
12-Sep-2022 13:29:59 Build CASA-C6DTF220-TTO15-1 completed.
12-Sep-2022 13:30:00 Running on server: post build plugin 'NCover Results Collector'
12-Sep-2022 13:30:00 Running on server: post build plugin 'Build Hanging Detection Configuration'
12-Sep-2022 13:30:00 Running on server: post build plugin 'Clover Delta Calculator'
12-Sep-2022 13:30:00 Running on server: post build plugin 'Maven Dependencies Postprocessor'
12-Sep-2022 13:30:00 All post build plugins have finished
12-Sep-2022 13:30:00 Generating build results summary...
12-Sep-2022 13:30:00 Saving build results to disk...
12-Sep-2022 13:30:00 Store variable context...
12-Sep-2022 13:30:00 Indexing build results...
12-Sep-2022 13:30:00 Finished building CASA-C6DTF220-TTO15-1.