Build: #2 was successful

Job: Test Tools Many Linux 2.28 was successful

Stages & jobs

  1. Tag

  2. Default Stage

  3. Test

Build log

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

25-Jul-2023 05:03:13 2023-07-25 09:03:13        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:14 2023-07-25 09:03:15        INFO        calibrater::correct        Finished correcting.
25-Jul-2023 05:03:15 2023-07-25 09:03:16        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:03:15 test_tool_calibrater.py::calibrater_test::test_selectVisBaseline
25-Jul-2023 05:03:15 Check that selectvis properly selects baseline/antenna
25-Jul-2023 05:03:15 65772 65772
25-Jul-2023 05:03:15 263088 263088
25-Jul-2023 05:03:15 PASSED
25-Jul-2023 05:03:15
25-Jul-2023 05:03:16 2023-07-25 09:03:16        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:03:16 2023-07-25 09:03:16        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:03:16 2023-07-25 09:03:16        INFO                Clearing all model records in MS header.
25-Jul-2023 05:03:16 2023-07-25 09:03:17        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:03:17 2023-07-25 09:03:18        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:03:18 2023-07-25 09:03:18        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:03:18 2023-07-25 09:03:18        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:03:18 2023-07-25 09:03:18        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
25-Jul-2023 05:03:18 2023-07-25 09:03:18        INFO        calibrater::reset        Reseting solve/apply state
25-Jul-2023 05:03:18 2023-07-25 09:03:18        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
25-Jul-2023 05:03:18 2023-07-25 09:03:18        INFO        Calibrater::selectvis+         Selecting on field: '0'
25-Jul-2023 05:03:20 2023-07-25 09:03:21        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 172260
25-Jul-2023 05:03:20 2023-07-25 09:03:21        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
25-Jul-2023 05:03:20 2023-07-25 09:03:21        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
25-Jul-2023 05:03:20 2023-07-25 09:03:21        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Jul-2023 05:03:20 2023-07-25 09:03:21        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Jul-2023 05:03:21 2023-07-25 09:03:21        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:21 2023-07-25 09:03:21        INFO        calibrater::correct        Beginning correct---------------------------
25-Jul-2023 05:03:21 2023-07-25 09:03:21        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Jul-2023 05:03:21 2023-07-25 09:03:21        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:22 2023-07-25 09:03:22        INFO        calibrater::correct        Finished correcting.
25-Jul-2023 05:03:23 2023-07-25 09:03:23        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:03:23 test_tool_calibrater.py::calibrater_test::test_selectVisField
25-Jul-2023 05:03:23 Check that the field is properly selected by selectvis
25-Jul-2023 05:03:23 PASSED
25-Jul-2023 05:03:23
25-Jul-2023 05:03:24 2023-07-25 09:03:24        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:03:24 2023-07-25 09:03:24        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:03:24 2023-07-25 09:03:24        INFO                Clearing all model records in MS header.
25-Jul-2023 05:03:24 2023-07-25 09:03:24        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        calibrater::reset        Reseting solve/apply state
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        Calibrater::selectvis+         Selecting on intent: '*AMPLI*'
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        Calibrater::selectvis        Selection did not drop any rows
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Jul-2023 05:03:25 2023-07-25 09:03:26        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Jul-2023 05:03:26 2023-07-25 09:03:26        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:26 2023-07-25 09:03:26        INFO        calibrater::correct        Beginning correct---------------------------
25-Jul-2023 05:03:26 2023-07-25 09:03:26        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Jul-2023 05:03:26 2023-07-25 09:03:26        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:28 2023-07-25 09:03:28        INFO        calibrater::correct        Finished correcting.
25-Jul-2023 05:03:28 2023-07-25 09:03:28        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:03:28 test_tool_calibrater.py::calibrater_test::test_selectVisIntent
25-Jul-2023 05:03:28 Check that the intent is properly selected by selectvis
25-Jul-2023 05:03:28 PASSED
25-Jul-2023 05:03:28
25-Jul-2023 05:03:29 2023-07-25 09:03:30        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:03:29 2023-07-25 09:03:30        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:03:29 2023-07-25 09:03:30        INFO                Clearing all model records in MS header.
25-Jul-2023 05:03:29 2023-07-25 09:03:30        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        calibrater::reset        Reseting solve/apply state
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        Calibrater::selectvis+         Selecting by observation IDs: '0'
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 318870
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Jul-2023 05:03:31 2023-07-25 09:03:31        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Jul-2023 05:03:31 2023-07-25 09:03:32        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:31 2023-07-25 09:03:32        INFO        calibrater::correct        Beginning correct---------------------------
25-Jul-2023 05:03:31 2023-07-25 09:03:32        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Jul-2023 05:03:31 2023-07-25 09:03:32        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:33 2023-07-25 09:03:34        INFO        calibrater::correct        Finished correcting.
25-Jul-2023 05:03:33 2023-07-25 09:03:34        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:03:33 test_tool_calibrater.py::calibrater_test::test_selectVisObs
25-Jul-2023 05:03:33 PASSED
25-Jul-2023 05:03:33
25-Jul-2023 05:03:34 2023-07-25 09:03:35        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:03:34 2023-07-25 09:03:35        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:03:34 2023-07-25 09:03:35        INFO                Clearing all model records in MS header.
25-Jul-2023 05:03:34 2023-07-25 09:03:35        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:03:36 2023-07-25 09:03:36        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        calibrater::reset        Reseting solve/apply state
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        Calibrater::selectvis+         Selecting on scan: '2'
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 15660
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        calibrater::correct        Beginning correct---------------------------
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:36 2023-07-25 09:03:37        INFO        calibrater::correct        Finished correcting.
25-Jul-2023 05:03:37 2023-07-25 09:03:38        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:03:37 test_tool_calibrater.py::calibrater_test::test_selectVisScan
25-Jul-2023 05:03:37 Check tht the scan is properly selected by selectvis
25-Jul-2023 05:03:37 PASSED
25-Jul-2023 05:03:37
25-Jul-2023 05:03:38 2023-07-25 09:03:39        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:03:38 2023-07-25 09:03:39        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:03:38 2023-07-25 09:03:39        INFO                Clearing all model records in MS header.
25-Jul-2023 05:03:38 2023-07-25 09:03:39        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:03:40 2023-07-25 09:03:40        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        calibrater::reset        Reseting solve/apply state
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::selectvis+         Selecting on spw: '0'
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 82215
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::selectvis        Frequency selection:
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::selectvis        .  Spw 0:0~7 (8 channels, step by 1)
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        calibrater::correct        Beginning correct---------------------------
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Jul-2023 05:03:40 2023-07-25 09:03:41        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:41 2023-07-25 09:03:41        INFO        calibrater::correct        Finished correcting.
25-Jul-2023 05:03:42 2023-07-25 09:03:42        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:03:42 test_tool_calibrater.py::calibrater_test::test_selectVisSpw
25-Jul-2023 05:03:42 Check that spw is properly selected by selectvis
25-Jul-2023 05:03:42 PASSED
25-Jul-2023 05:03:42
25-Jul-2023 05:03:42 2023-07-25 09:03:43        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:03:42 2023-07-25 09:03:43        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:03:42 2023-07-25 09:03:43        INFO                Clearing all model records in MS header.
25-Jul-2023 05:03:43 2023-07-25 09:03:43        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:03:44 2023-07-25 09:03:44        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        calibrater::reset        Reseting solve/apply state
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        Calibrater::selectvis+         Selecting on time: '>04:38:23'
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 313200
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        calibrater::correct        Beginning correct---------------------------
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Jul-2023 05:03:44 2023-07-25 09:03:45        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:47 2023-07-25 09:03:47        INFO        calibrater::correct        Finished correcting.
25-Jul-2023 05:03:48 2023-07-25 09:03:48        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:03:48 test_tool_calibrater.py::calibrater_test::test_selectVisTime
25-Jul-2023 05:03:48 Check that time is properly selected by selectvis
25-Jul-2023 05:03:48 PASSED
25-Jul-2023 05:03:48
25-Jul-2023 05:03:48 2023-07-25 09:03:48        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:03:48 2023-07-25 09:03:48        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:03:48 2023-07-25 09:03:48        INFO                Clearing all model records in MS header.
25-Jul-2023 05:03:48 2023-07-25 09:03:48        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:03:49 2023-07-25 09:03:50        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:03:50 2023-07-25 09:03:50        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:03:50 2023-07-25 09:03:50        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:03:50 2023-07-25 09:03:50        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
25-Jul-2023 05:03:50 2023-07-25 09:03:50        INFO        calibrater::reset        Reseting solve/apply state
25-Jul-2023 05:03:50 2023-07-25 09:03:50        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
25-Jul-2023 05:03:50 2023-07-25 09:03:50        INFO        Calibrater::selectvis+         Selecting on uvrange: '> 500000lambda'
25-Jul-2023 05:03:50 2023-07-25 09:03:51        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 103321
25-Jul-2023 05:03:50 2023-07-25 09:03:51        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
25-Jul-2023 05:03:50 2023-07-25 09:03:51        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
25-Jul-2023 05:03:50 2023-07-25 09:03:51        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Jul-2023 05:03:50 2023-07-25 09:03:51        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Jul-2023 05:03:50 2023-07-25 09:03:51        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:50 2023-07-25 09:03:51        INFO        calibrater::correct        Beginning correct---------------------------
25-Jul-2023 05:03:50 2023-07-25 09:03:51        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Jul-2023 05:03:50 2023-07-25 09:03:51        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:03:52 2023-07-25 09:03:52        INFO        calibrater::correct        Finished correcting.
25-Jul-2023 05:03:53 2023-07-25 09:03:53        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:03:53 test_tool_calibrater.py::calibrater_test::test_selectVisUVrange
25-Jul-2023 05:03:53 Check that selectvis properly selects for uvrange
25-Jul-2023 05:03:53 PASSED
25-Jul-2023 05:03:53
25-Jul-2023 05:03:53 2023-07-25 09:03:54        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:03:53 2023-07-25 09:03:54        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:03:53 2023-07-25 09:03:54        INFO                Clearing all model records in MS header.
25-Jul-2023 05:03:53 2023-07-25 09:03:54        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        calibrater::parsecallibfile        Beginning parsecallibfile-)-------
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        calibrater::setcallib        Beginning setcallib---------
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        Calibrater::setcallib2(callib)        Arranging to APPLY:
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO                .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO                .   0:
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        +             MS: obs= scan= fld= intent= spw=
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        +             CT: tinterp=linear finterp=
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        +                 obsmap=[]         scanmap=[]         fldmap=[]
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        +                 spwmap=[]         antmap=[]
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        calibrater::corrupt        Beginning corrupt---------------------------
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Jul-2023 05:03:55 2023-07-25 09:03:55        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
25-Jul-2023 05:03:57 2023-07-25 09:03:57        INFO        calibrater::corrupt        Finished corrupting.
25-Jul-2023 05:03:57 2023-07-25 09:03:58        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:03:57 test_tool_calibrater.py::calibrater_test::test_setCalLib
25-Jul-2023 05:03:57 Check that a provided cal table can be used to corrupt the MODEL_DATA 
25-Jul-2023 05:03:57 PASSED
25-Jul-2023 05:03:57
25-Jul-2023 05:03:58 2023-07-25 09:03:58        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:03:58 2023-07-25 09:03:58        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:03:58 2023-07-25 09:03:58        INFO                Clearing all model records in MS header.
25-Jul-2023 05:03:58 2023-07-25 09:03:58        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:03:59 2023-07-25 09:04:00        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:03:59 2023-07-25 09:04:00        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:03:59 2023-07-25 09:04:00        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:03:59 2023-07-25 09:04:00        INFO        Calibrater::setCorrDepFlags        Setting correlation dependent flags = True
25-Jul-2023 05:04:00 2023-07-25 09:04:00        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:04:00 test_tool_calibrater.py::calibrater_test::test_setCorrDepFlags
25-Jul-2023 05:04:00 Check that corrdepflags will be checked 
25-Jul-2023 05:04:00 PASSED
25-Jul-2023 05:04:00
25-Jul-2023 05:04:00 2023-07-25 09:04:01        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:04:00 2023-07-25 09:04:01        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
25-Jul-2023 05:04:00 2023-07-25 09:04:01        INFO                Clearing all model records in MS header.
25-Jul-2023 05:04:00 2023-07-25 09:04:01        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:04:00 2023-07-25 09:04:01        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:04:00 2023-07-25 09:04:01        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
25-Jul-2023 05:04:00 2023-07-25 09:04:01        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:04:00 2023-07-25 09:04:01        INFO        calibrater::smooth        Beginning smooth--(MSSelection version)-------
25-Jul-2023 05:04:00 2023-07-25 09:04:01        INFO        Calibrater::smooth        Beginning smoothing/interpolating method.
25-Jul-2023 05:04:00 2023-07-25 09:04:01        WARN                Found pre-v4.1 caltable (/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/ngc5921.gcal); attempting to update...
25-Jul-2023 05:04:00 2023-07-25 09:04:01        WARN        +        SUCCEEDED: trivial OBSERVATION/OBSERVATION_ID have been added.
25-Jul-2023 05:04:01 2023-07-25 09:04:01        INFO        Calibrater::smooth        Storing result in testcalout.cal
25-Jul-2023 05:04:01 2023-07-25 09:04:01        INFO                Writing solutions to table: testcalout.cal
25-Jul-2023 05:04:01 2023-07-25 09:04:02        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:04:02 test_tool_calibrater.py::calibrater_test::test_smoothedCalTables
25-Jul-2023 05:04:02 Check that the smooth command creates a smoothed cal table 
25-Jul-2023 05:04:02 PASSED
25-Jul-2023 05:04:02
25-Jul-2023 05:04:02 2023-07-25 09:04:02        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
25-Jul-2023 05:04:02 2023-07-25 09:04:02        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
25-Jul-2023 05:04:02 2023-07-25 09:04:02        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
25-Jul-2023 05:04:02 2023-07-25 09:04:02        INFO                Clearing all model records in MS header.
25-Jul-2023 05:04:02 2023-07-25 09:04:02        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        calibrater::setsolvebandpoly        Beginning setsolvebandpoly--(MSSelection version)-------
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        Calibrater::setsolvebandpoly        Arranging to SOLVE:
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        Calibrater::setsolvebandpoly        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        calibrater::solve        Beginning solve-----------------------------
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        Calibrater::solve        .   (None)
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        Calibrater::solve        The following calibration term is arranged for solve:
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        Calibrater::solve        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        Calibrater::solve        Solving for BPOLY
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO                For solint = inf, found 7 solution intervals.
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.059507/0.0681762/0.0765806
25-Jul-2023 05:04:02 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:03 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.336529/0.750193/1.6779
25-Jul-2023 05:04:03 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:03 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0630839/0.0689061/0.0756384
25-Jul-2023 05:04:03 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:03 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.279482/0.74979/1.59355
25-Jul-2023 05:04:03 2023-07-25 09:04:03        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
25-Jul-2023 05:04:03 2023-07-25 09:04:03        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0672607/0.089081/0.111106
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.31075/3.32219/4.29305
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0652822/0.0889198/0.116912
25-Jul-2023 05:04:03 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.44269/3.26616/4.3519
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350681/0.587724/0.894558
25-Jul-2023 05:04:04 2023-07-25 09:04:04        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:06 2023-07-25 09:04:06        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.296/59.0077/111.454
25-Jul-2023 05:04:06 2023-07-25 09:04:06        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:06 2023-07-25 09:04:06        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.354236/0.581733/0.988903
25-Jul-2023 05:04:06 2023-07-25 09:04:06        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 17.2419/56.3249/117.264
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0850169/0.113968/0.15276
25-Jul-2023 05:04:08 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.67561/5.33243/8.17296
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0784236/0.114345/0.159908
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.46392/5.27232/7.20673
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
25-Jul-2023 05:04:09 2023-07-25 09:04:09        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
25-Jul-2023 05:04:09 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
25-Jul-2023 05:04:09 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:09 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0703936/0.102501/0.131676
25-Jul-2023 05:04:09 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:09 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.98728/4.47977/5.84264
25-Jul-2023 05:04:09 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:09 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0751114/0.102994/0.138529
25-Jul-2023 05:04:09 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.18629/4.47068/5.8941
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350626/0.604424/0.974388
25-Jul-2023 05:04:10 2023-07-25 09:04:10        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:12 2023-07-25 09:04:12        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.2542/59.3511/120.719
25-Jul-2023 05:04:12 2023-07-25 09:04:12        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:12 2023-07-25 09:04:12        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.382806/0.598119/0.941571
25-Jul-2023 05:04:12 2023-07-25 09:04:12        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:14 2023-07-25 09:04:15        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 20.0637/57.2112/118.101
25-Jul-2023 05:04:14 2023-07-25 09:04:15        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
25-Jul-2023 05:04:14 2023-07-25 09:04:15        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0750931/0.0978998/0.125897
25-Jul-2023 05:04:20 2023-07-25 09:04:20        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:20 2023-07-25 09:04:21        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.72932/3.98787/5.33715
25-Jul-2023 05:04:20 2023-07-25 09:04:21        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
25-Jul-2023 05:04:20 2023-07-25 09:04:21        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0658572/0.0972125/0.140794
25-Jul-2023 05:04:20 2023-07-25 09:04:21        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
25-Jul-2023 05:04:20 2023-07-25 09:04:21        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.97943/3.97841/5.72903
25-Jul-2023 05:04:20 2023-07-25 09:04:21        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
25-Jul-2023 05:04:20 2023-07-25 09:04:21        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
25-Jul-2023 05:04:20 2023-07-25 09:04:21        INFO        Calibrater::solve          Found good BPOLY solutions in 7 slots.
25-Jul-2023 05:04:20 2023-07-25 09:04:21        INFO        calibrater::solve        Finished solving.
25-Jul-2023 05:04:21 2023-07-25 09:04:21        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
25-Jul-2023 05:04:21 test_tool_calibrater.py::calibrater_test::test_solveBandpass
25-Jul-2023 05:04:21 Check that solve band poly creates the output table
25-Jul-2023 05:04:21 PASSED
25-Jul-2023 05:04:21
25-Jul-2023 05:04:21 2023-07-25 09:04:21        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
25-Jul-2023 05:04:21 2023-07-25 09:04:21        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
25-Jul-2023 05:04:21 2023-07-25 09:04:21        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
25-Jul-2023 05:04:21 2023-07-25 09:04:21        INFO                Clearing all model records in MS header.
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        calibrater::setsolvegainspline        Beginning setsolvegainspline--(MSSelection version)-------
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        Calibrater::setsolvegainspline        Arranging to SOLVE:
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        Calibrater::setsolvegainspline        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        calibrater::solve        Beginning solve-----------------------------
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        Calibrater::solve        .   (None)
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        Calibrater::solve        The following calibration term is arranged for solve:
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        Calibrater::solve        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Fitting time-dependent cubic splines.
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Solving for phase splines with splinetime= 10800
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Solving for amplitude splines with splinetime= 10800
25-Jul-2023 05:04:21 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1331+30500002_0, spw= 0, nchan= 63
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1445+09900002_0, spw= 0, nchan= 63
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Accumulating data for:  field= N5921_2, spw= 0, nchan= 63
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Number of timestamps in data = 60
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::getKnots()         Gridded splinetime = 5280 sec.
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Number of cubic spline control points = 4
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Number of cubic spline knots = 8
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Number of cubic spline segments = 1
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Fitting amplitude spline.
25-Jul-2023 05:04:22 test_tool_calibrater.py::calibrater_test::test_solveGainspline  DPBTRF ;problem leading minor           23
25-Jul-2023 05:04:22 DPBTRF ;problem leading minor           23
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Searching for and correcting phase-wraps on each baseline.
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::solve()         Fitting phase spline.
25-Jul-2023 05:04:22 DPBTRF ;problem leading minor           22
25-Jul-2023 05:04:22 DPBTRF ;problem leading minor           22
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        GJonesSpline::updateCalTable         Storing solutions in table gainspline
25-Jul-2023 05:04:22 2023-07-25 09:04:22        INFO        calibrater::solve        Finished solving.
25-Jul-2023 05:04:22 2023-07-25 09:04:23        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
25-Jul-2023 05:04:22
25-Jul-2023 05:04:22 Check that solve gain spline creates the output table 
25-Jul-2023 05:04:22 PASSED
25-Jul-2023 05:04:22
25-Jul-2023 05:04:22 2023-07-25 09:04:23        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:04:22 2023-07-25 09:04:23        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:04:22 2023-07-25 09:04:23        INFO                Clearing all model records in MS header.
25-Jul-2023 05:04:22 2023-07-25 09:04:23        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:04:24 2023-07-25 09:04:24        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:04:24 2023-07-25 09:04:25        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:04:24 2023-07-25 09:04:25        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:04:24 2023-07-25 09:04:25        INFO        calibrater::specifycal        Beginning specifycal-----------------------
25-Jul-2023 05:04:24 2023-07-25 09:04:25        INFO                Creating G Jones table from specified parameters.
25-Jul-2023 05:04:24 2023-07-25 09:04:25        INFO                Generating 'G' corrections.
25-Jul-2023 05:04:24 2023-07-25 09:04:25        INFO                Specified parameter(s) (per spw and pol) repeated on all antennas.
25-Jul-2023 05:04:24 2023-07-25 09:04:25        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
25-Jul-2023 05:04:24 2023-07-25 09:04:25        INFO                spwId=1 antId=<all> polId=<all> parameter= 3   (ip0,ip1 = [0, 0, 0],[1, 0, 9])
25-Jul-2023 05:04:24 2023-07-25 09:04:25        INFO                Writing solutions to table: testcalout.cal
25-Jul-2023 05:04:25 2023-07-25 09:04:25        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:04:25 test_tool_calibrater.py::calibrater_test::test_specifyCal
25-Jul-2023 05:04:25 Check that specifycal can set values for specific spws and antennas
25-Jul-2023 05:04:25 PASSED
25-Jul-2023 05:04:25
25-Jul-2023 05:04:25 2023-07-25 09:04:26        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
25-Jul-2023 05:04:25 2023-07-25 09:04:26        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
25-Jul-2023 05:04:25 2023-07-25 09:04:26        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:04:25 2023-07-25 09:04:26        INFO                Clearing all model records in MS header.
25-Jul-2023 05:04:25 2023-07-25 09:04:26        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:04:27 2023-07-25 09:04:27        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:04:27 2023-07-25 09:04:27        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:04:27 2023-07-25 09:04:27        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:04:27 2023-07-25 09:04:27        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Jul-2023 05:04:27 2023-07-25 09:04:27        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::setsolve        Arranging to SOLVE:
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::setsolve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::state        The following calibration terms are arranged for apply:
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::state        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::state        The following calibration term is arranged for solve:
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::state        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        calibrater::solve        Beginning solve-----------------------------
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::solve        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::solve        The following calibration term is arranged for solve:
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO        Calibrater::solve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO                Solving for Mf Mueller
25-Jul-2023 05:04:27 2023-07-25 09:04:28        INFO                For solint = inf, found 84 solution intervals.
25-Jul-2023 05:04:28 2023-07-25 09:04:29        INFO                  Found good Mf Mueller solutions in 84 intervals.
25-Jul-2023 05:04:28 2023-07-25 09:04:29        INFO                Writing solutions to table: output.ms
25-Jul-2023 05:04:29 2023-07-25 09:04:30        INFO        calibrater::solve        Finished solving.
25-Jul-2023 05:04:29 2023-07-25 09:04:30        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
25-Jul-2023 05:04:29 test_tool_calibrater.py::calibrater_test::test_standardPath
25-Jul-2023 05:04:29 open setapply setsolve state solve close 
25-Jul-2023 05:04:29 PASSED
25-Jul-2023 05:04:29
25-Jul-2023 05:04:30 2023-07-25 09:04:30        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:04:30 2023-07-25 09:04:30        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:04:30 2023-07-25 09:04:30        INFO                Clearing all model records in MS header.
25-Jul-2023 05:04:30 2023-07-25 09:04:30        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        name::        No table opened.
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::        The Table Cache has the following 16 entries:
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            0: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            1: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            2: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            3: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            4: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            5: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            6: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            7: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            8: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            9: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            10: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            11: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            12: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            13: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            14: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
25-Jul-2023 05:04:31 2023-07-25 09:04:32        INFO        showcache::            15: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
25-Jul-2023 05:04:32 2023-07-25 09:04:32        INFO        name::        No table opened.
25-Jul-2023 05:04:32 2023-07-25 09:04:32        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:32 2023-07-25 09:04:32        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:04:32 test_tool_calibrater.py::calibrater_test::test_takesMs
25-Jul-2023 05:04:32 Check that the calibrater tool can open and close an MS 
25-Jul-2023 05:04:32 PASSED
25-Jul-2023 05:04:32
25-Jul-2023 05:04:32 2023-07-25 09:04:32        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Jul-2023 05:04:32 2023-07-25 09:04:32        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Jul-2023 05:04:32 2023-07-25 09:04:32        INFO                Clearing all model records in MS header.
25-Jul-2023 05:04:32 2023-07-25 09:04:33        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Jul-2023 05:04:33 2023-07-25 09:04:34        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Jul-2023 05:04:34 2023-07-25 09:04:34        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Jul-2023 05:04:34 2023-07-25 09:04:34        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Jul-2023 05:04:34 2023-07-25 09:04:34        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Jul-2023 05:04:34 2023-07-25 09:04:34        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Jul-2023 05:04:34 2023-07-25 09:04:34        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:04:34 2023-07-25 09:04:34        INFO        calibrater::correct        Beginning correct---------------------------
25-Jul-2023 05:04:34 2023-07-25 09:04:34        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Jul-2023 05:04:34 2023-07-25 09:04:34        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Jul-2023 05:04:36 2023-07-25 09:04:36        INFO        calibrater::correct        Finished correcting.
25-Jul-2023 05:04:36 2023-07-25 09:04:37        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Jul-2023 05:04:36 test_tool_calibrater.py::calibrater_test::test_writeToCorrected
25-Jul-2023 05:04:36 Check that the tool writes to the CORRECTED_DATA column 
25-Jul-2023 05:04:36 PASSED
25-Jul-2023 05:04:36
25-Jul-2023 05:04:36 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml -
25-Jul-2023 05:04:36 ================== 24 passed, 1 warning in 114.20s (0:01:54) ===================
25-Jul-2023 05:04:37 ============================= test session starts ==============================
25-Jul-2023 05:04:37 platform linux -- Python 3.8.7, pytest-7.4.0, pluggy-1.2.0 -- /home/casatest/venv/bin/python3.8
25-Jul-2023 05:04:37 cachedir: .pytest_cache
25-Jul-2023 05:04:37 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta
25-Jul-2023 05:04:37 configfile: pytest.ini
25-Jul-2023 05:04:37 collecting ... collected 9 items
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE                Exception Reported: Error in QuantumHolder::fromString with input string "bogus": Illegal input units or format
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE        +         in converting quantity
25-Jul-2023 05:04:37 test_tool_quanta.py::quanta_convert_test::test_exceptions
25-Jul-2023 05:04:37 Test various exception cases
25-Jul-2023 05:04:37 PASSED
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 test_tool_quanta.py::quanta_convert_test::test_valid
25-Jul-2023 05:04:37 Test specifying the quantity as a scalar
25-Jul-2023 05:04:37 PASSED
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 test_tool_quanta.py::quanta_convert_test::test_value_as_string
25-Jul-2023 05:04:37 Test specifying value as a string
25-Jul-2023 05:04:37 PASSED
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE                Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE        +         in converting quantity
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE                Exception Reported: Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE        +         in converting quantity
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE                Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE        +         in converting quantity
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE                Exception Reported: Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE        +         in converting quantity
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE                Exception Reported: Unit::check Illegal unit string 'zz'
25-Jul-2023 05:04:37 2023-07-25 09:04:37        SEVERE                Exception Reported: Unit::check Illegal unit string 'zz'
25-Jul-2023 05:04:37 test_tool_quanta.py::quanta_quantity_test::test_exceptions
25-Jul-2023 05:04:37 Test various exception cases
25-Jul-2023 05:04:37 PASSED
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 test_tool_quanta.py::quanta_quantity_test::test_multidimensional_arra_value
25-Jul-2023 05:04:37 Test specifying the quantity as a multidimensional array
25-Jul-2023 05:04:37 PASSED
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 test_tool_quanta.py::quanta_quantity_test::test_scalar_value
25-Jul-2023 05:04:37 Test specifying the quantity as a scalar
25-Jul-2023 05:04:37 PASSED
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 test_tool_quanta.py::quanta_quantity_test::test_value_as_record
25-Jul-2023 05:04:37 Test value as record (eg already a quantity).
25-Jul-2023 05:04:37 This is essentially a reflection operator.
25-Jul-2023 05:04:37 PASSED
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 test_tool_quanta.py::quanta_quantity_test::test_value_as_string
25-Jul-2023 05:04:37 Test specifying value as a string
25-Jul-2023 05:04:37 PASSED
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 test_tool_quanta.py::quanta_quantity_test::test_vector_value
25-Jul-2023 05:04:37 Test specifying the quantity as a vector
25-Jul-2023 05:04:37 PASSED
25-Jul-2023 05:04:37
25-Jul-2023 05:04:37 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_quanta/nose.xml -
25-Jul-2023 05:04:37 ========================= 9 passed, 1 warning in 0.44s =========================
25-Jul-2023 05:04:37 ============================= test session starts ==============================
25-Jul-2023 05:04:37 platform linux -- Python 3.8.7, pytest-7.4.0, pluggy-1.2.0 -- /home/casatest/venv/bin/python3.8
25-Jul-2023 05:04:37 cachedir: .pytest_cache
25-Jul-2023 05:04:37 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor
25-Jul-2023 05:04:37 configfile: pytest.ini
25-Jul-2023 05:04:38 collecting ... collected 9 items
25-Jul-2023 05:04:38
25-Jul-2023 05:04:38
25-Jul-2023 05:04:38 2023-07-25 09:04:38        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:38 2023-07-25 09:04:38        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:38 2023-07-25 09:04:38        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
25-Jul-2023 05:04:38 2023-07-25 09:04:38        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
25-Jul-2023 05:04:38 2023-07-25 09:04:38        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
25-Jul-2023 05:04:38 2023-07-25 09:04:38        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:38 2023-07-25 09:04:38        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)
25-Jul-2023 05:04:38 2023-07-25 09:04:38        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:38 2023-07-25 09:04:38        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)
25-Jul-2023 05:04:38 2023-07-25 09:04:38        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
25-Jul-2023 05:04:38 2023-07-25 09:04:39        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
25-Jul-2023 05:04:38 2023-07-25 09:04:39        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:38 2023-07-25 09:04:39        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)
25-Jul-2023 05:04:38 2023-07-25 09:04:39        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
25-Jul-2023 05:04:38 2023-07-25 09:04:39        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
25-Jul-2023 05:04:38 2023-07-25 09:04:39        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
25-Jul-2023 05:04:38 2023-07-25 09:04:39        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:38 2023-07-25 09:04:39        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)
25-Jul-2023 05:04:38 2023-07-25 09:04:39        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:38 2023-07-25 09:04:39        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)
25-Jul-2023 05:04:38 2023-07-25 09:04:39        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
25-Jul-2023 05:04:38 2023-07-25 09:04:39        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
25-Jul-2023 05:04:39 2023-07-25 09:04:39        INFO        name::        No table opened.
25-Jul-2023 05:04:39 2023-07-25 09:04:39        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:39 test_tool_image_pbcor.py::ia_pbcor_test::test_4d_image_with_2d_pb
25-Jul-2023 05:04:39 ia.pbcor: Test full image divide with cutoff. Primary beam is 2 D, image is 4 D
25-Jul-2023 05:04:39 PASSED
25-Jul-2023 05:04:39
25-Jul-2023 05:04:39 2023-07-25 09:04:39        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [5, 5, 1, 1]
25-Jul-2023 05:04:39 2023-07-25 09:04:40        INFO        name::        No table opened.
25-Jul-2023 05:04:39 2023-07-25 09:04:40        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:39 test_tool_image_pbcor.py::ia_pbcor_test::test_diff_spectral_coordinate
25-Jul-2023 05:04:39 Verify fix that a different spectral coordinates in target and template don't matter, CAS-5096
25-Jul-2023 05:04:39 PASSED
25-Jul-2023 05:04:39
25-Jul-2023 05:04:39 2023-07-25 09:04:40        INFO        ImageFactory::createImage        Created Paged image 't_in.im' of shape [20, 20, 4, 20] with float valued pixels.
25-Jul-2023 05:04:40 2023-07-25 09:04:40        INFO        ImageFactory::createImage        Created Paged image 't_pb_in.im' of shape [20, 20, 4, 20] with float valued pixels.
25-Jul-2023 05:04:40 2023-07-25 09:04:40        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
25-Jul-2023 05:04:40 2023-07-25 09:04:40        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
25-Jul-2023 05:04:40 2023-07-25 09:04:40        INFO        CasacRegionManager::fromBCS        Using polarizations I
25-Jul-2023 05:04:40 2023-07-25 09:04:40        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:40 2023-07-25 09:04:40        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [19, 19, 0, 19]  (00:00:40.000, -00.10.00.001, I, 1.41499e+09Hz to 23:59:24.000, +00.09.00.001, I, 1.41501e+09Hz)
25-Jul-2023 05:04:40 2023-07-25 09:04:40        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:40 2023-07-25 09:04:40        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [19, 19, 0, 19]  (00:00:40.000, -00.10.00.001, I, 1.41499e+09Hz to 23:59:24.000, +00.09.00.001, I, 1.41501e+09Hz)
25-Jul-2023 05:04:40 2023-07-25 09:04:40        INFO        SubImageFactory::createImage        Creating image 't_out.im' of shape [20, 20, 1, 20]
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        name::        No table opened.
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:40 test_tool_image_pbcor.py::ia_pbcor_test::test_empty_region_and_stokes
25-Jul-2023 05:04:40 Test specifying stokes with empty region works (CAS-11708)
25-Jul-2023 05:04:40 PASSED
25-Jul-2023 05:04:40
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Exception Reported: Exception: File name is empty.
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::open (file src/tools/image/image_cmpt.cc, line 4208)+        ... thrown by static void casa::ImageFactory::_checkInfile(const casacore::String&) at File: /source/casa6/casatools/src/code/imageanalysis/ImageAnalysis/ImageFactory2.cc, line: 371
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Exception Reported: Exception: File totally_bogus does not exist..
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::open (file src/tools/image/image_cmpt.cc, line 4208)+        ... thrown by static void casa::ImageFactory::_checkInfile(const casacore::String&) at File: /source/casa6/casatools/src/code/imageanalysis/ImageAnalysis/ImageFactory2.cc, line: 375
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Exception: The image filename is empty.
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)+        ... thrown by static void casacore::ImageUtilities::openImage(casacore::ImageInterface<T>*&, const casacore::String&) [with T = float] at File: /source/casainstall/include/casacore/images/Images/ImageUtilities2.tcc, line: 255
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Exception: File 'totally_bogus2' does not exist.
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)+        ... thrown by static void casacore::ImageUtilities::openImage(casacore::ImageInterface<T>*&, const casacore::String&) [with T = float] at File: /source/casainstall/include/casacore/images/Images/ImageUtilities2.tcc, line: 260
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        ImageInputProcessor::_process        Requested output image /bogusplace/bogusimage cannot be created so will not be written
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: 2023-07-25 09:04:41        SEVERE        ImageInputProcessor::_process        Requested output image /bogusplace/bogusimage cannot be created so will not be written
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        WARN                /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/pbtest1_im.fits is not a valid or readable table
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE                Cannot read image /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/pbtest1_im.fits to get region bogus_region
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Unable to open region file or region table description bogus_region in image /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/pbtest1_im.fits at File: /source/casa6/casatools/src/code/imageanalysis/Regions/CasacRegionManager.cc, line: 486
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Unknown mode zz
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Coordinate systems of image and template are different: The DirectionCoordinates have differing LinearXform components at File: /source/casa6/casatools/src/code/imageanalysis/ImageAnalysis/ImagePrimaryBeamCorrector.cc, line: 141
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        name::        No table opened.
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:40 test_tool_image_pbcor.py::ia_pbcor_test::test_exceptions
25-Jul-2023 05:04:40 ia.pbcor: Test various exception cases
25-Jul-2023 05:04:40 PASSED
25-Jul-2023 05:04:40
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:40 2023-07-25 09:04:41        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)
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:40 2023-07-25 09:04:41        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)
25-Jul-2023 05:04:40 2023-07-25 09:04:41        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:41 2023-07-25 09:04:41        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)
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:41 2023-07-25 09:04:41        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)
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        name::        No table opened.
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:41 test_tool_image_pbcor.py::ia_pbcor_test::test_full_image_divide
25-Jul-2023 05:04:41 ia.pbcor: Test full image divide
25-Jul-2023 05:04:41 PASSED
25-Jul-2023 05:04:41
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:41 2023-07-25 09:04:41        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)
25-Jul-2023 05:04:41 2023-07-25 09:04:41        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:41 2023-07-25 09:04:41        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)
25-Jul-2023 05:04:41 2023-07-25 09:04:42        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
25-Jul-2023 05:04:41 2023-07-25 09:04:42        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
25-Jul-2023 05:04:41 2023-07-25 09:04:42        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:41 2023-07-25 09:04:42        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
25-Jul-2023 05:04:41 2023-07-25 09:04:42        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:41 2023-07-25 09:04:42        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)
25-Jul-2023 05:04:41 2023-07-25 09:04:42        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:41 2023-07-25 09:04:42        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)
25-Jul-2023 05:04:42 2023-07-25 09:04:42        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
25-Jul-2023 05:04:42 2023-07-25 09:04:42        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
25-Jul-2023 05:04:42 2023-07-25 09:04:42        INFO        name::        No table opened.
25-Jul-2023 05:04:42 2023-07-25 09:04:42        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:42 test_tool_image_pbcor.py::ia_pbcor_test::test_full_image_using_cutoff
25-Jul-2023 05:04:42 ia.pbcor: Test full image divide with cutoff
25-Jul-2023 05:04:42 PASSED
25-Jul-2023 05:04:42
25-Jul-2023 05:04:42 2023-07-25 09:04:42        INFO        ImageFactory::createImage        Created Paged image 'zz.im' of shape [20, 20] with float valued pixels.
25-Jul-2023 05:04:42 2023-07-25 09:04:43        INFO        SubImageFactory::createImage        Creating image 'pb_out.im' of shape [20, 20]
25-Jul-2023 05:04:42 2023-07-25 09:04:42        INFO        ::::ImageFactory::createImage        Created Paged image 'zz.im' of shape [20, 20] with float valued pixels.
25-Jul-2023 05:04:42 2023-07-25 09:04:42        INFO        ::::image::fromshape        Ran ia.fromshape
25-Jul-2023 05:04:42 2023-07-25 09:04:42        INFO        ::::image::fromshape        ia.fromshape(outfile="zz.im", shape=[20,20], csys={...}, linear=false, overwrite=false, log=true, type="f")
25-Jul-2023 05:04:42 2023-07-25 09:04:43        INFO        ::::image::pbcor        Ran ia.pbcor
25-Jul-2023 05:04:42 2023-07-25 09:04:43        INFO        ::::image::pbcor        ia.pbcor(pbimage="(...)", outfile="pb_out.im", overwrite=false, box="", region={...}, chans="", stokes="", mask="", mode="divide", cutoff=-1.000000, stretch=false)
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        name::        No table opened.
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:43 test_tool_image_pbcor.py::ia_pbcor_test::test_history
25-Jul-2023 05:04:43 Test history records are written
25-Jul-2023 05:04:43 PASSED
25-Jul-2023 05:04:43
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        ImageFactory::createImage        Created Paged image 'mult_pb.im' of shape [113, 76] with float valued pixels.
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:43 2023-07-25 09:04:43        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)
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:43 2023-07-25 09:04:43        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)
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
25-Jul-2023 05:04:43 2023-07-25 09:04:43        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
25-Jul-2023 05:04:43 2023-07-25 09:04:44        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:43 2023-07-25 09:04:44        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)
25-Jul-2023 05:04:43 2023-07-25 09:04:44        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
25-Jul-2023 05:04:43 2023-07-25 09:04:44        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
25-Jul-2023 05:04:43 2023-07-25 09:04:44        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
25-Jul-2023 05:04:43 2023-07-25 09:04:44        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:43 2023-07-25 09:04:44        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)
25-Jul-2023 05:04:43 2023-07-25 09:04:44        INFO        ImageRegion::fromRecord        Selected bounding box :
25-Jul-2023 05:04:43 2023-07-25 09:04:44        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)
25-Jul-2023 05:04:43 2023-07-25 09:04:44        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
25-Jul-2023 05:04:43 2023-07-25 09:04:44        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
25-Jul-2023 05:04:44 2023-07-25 09:04:44        INFO        name::        No table opened.
25-Jul-2023 05:04:44 2023-07-25 09:04:44        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:44 test_tool_image_pbcor.py::ia_pbcor_test::test_multiply
25-Jul-2023 05:04:44 ia.pbcor: Test full image multiply with cutoff. Primary beam is 2 D, image is 4 D
25-Jul-2023 05:04:44 PASSED
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 2023-07-25 09:04:44        INFO        ImageFactory::createImage        Created Temp image  of shape [113, 76, 1, 1] with float valued pixels.
25-Jul-2023 05:04:44 2023-07-25 09:04:44        INFO        SubImageFactory::createImage        Creating image 'maskim' of shape [113, 76, 1, 1]
25-Jul-2023 05:04:44 2023-07-25 09:04:44        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:44 2023-07-25 09:04:44        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        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: /source/casainstall/include/casacpp/imageanalysis/ImageAnalysis/SubImageFactory.tcc, line: 306
25-Jul-2023 05:04:44 2023-07-25 09:04:44        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
25-Jul-2023 05:04:44 2023-07-25 09:04:44        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
25-Jul-2023 05:04:44 2023-07-25 09:04:44        INFO        SubImageFactory::createImage        Creating image 'blahblah' of shape [113, 76, 5, 2]
25-Jul-2023 05:04:44 2023-07-25 09:04:45        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
25-Jul-2023 05:04:44 2023-07-25 09:04:45        INFO        name::        No table opened.
25-Jul-2023 05:04:44 2023-07-25 09:04:45        INFO        showcache::        The Table Cache is empty.
25-Jul-2023 05:04:44 test_tool_image_pbcor.py::ia_pbcor_test::test_stretch
25-Jul-2023 05:04:44 ia.pbcor(): Test stretch parameter
25-Jul-2023 05:04:44 PASSED
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_pbcor/nose.xml -
25-Jul-2023 05:04:44 ========================= 9 passed, 1 warning in 6.88s =========================
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_sdm/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_simulator/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_imagepol/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_agentflagger/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_agentflagger
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_agentflagger/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_agentflagger/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_vpmanager/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_vpmanager
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_vpmanager/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_vpmanager/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_regionmanager/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_ms/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_regrid/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_componentlist/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_componentlist
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_componentlist/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_componentlist/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_measures/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_measures/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_deviation/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_msmetadata/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_linearmosaic/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_linearmosaic
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_linearmosaic/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_linearmosaic/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_table/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_table
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_table/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_table/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_quanta/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta/
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/
25-Jul-2023 05:04:44 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor
25-Jul-2023 05:04:44
25-Jul-2023 05:04:44 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_pbcor/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/
25-Jul-2023 05:04:46 total 184624
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci 15001    112234 Jul 25 08:43 casatestutils-6.6.0.3a14023.dev1+dirty-py3-none-any.whl
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci 15001 188774174 Jul 25 08:43 casatools-6.6.0.3a14023.dev1+dirty-cp38-cp38-manylinux_2_28_x86_64.whl
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       4314 Jul 25 09:04 test_tool_agentflagger.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        980 Jul 25 09:04 test_tool_calanalysis.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2632 Jul 25 09:04 test_tool_calibrater.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        545 Jul 25 09:04 test_tool_componentlist.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       7441 Jul 25 09:04 test_tool_coordsys.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root      12706 Jul 25 09:04 test_tool_image.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2194 Jul 25 09:04 test_tool_image_collapse.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1022 Jul 25 09:04 test_tool_image_deviation.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2550 Jul 25 09:04 test_tool_image_fitprofile.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1152 Jul 25 09:04 test_tool_image_pbcor.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        857 Jul 25 09:04 test_tool_image_pv.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        980 Jul 25 09:04 test_tool_image_rebin.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1310 Jul 25 09:04 test_tool_image_regrid.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1507 Jul 25 09:04 test_tool_image_statistics.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        739 Jul 25 09:04 test_tool_image_subimage.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        864 Jul 25 09:04 test_tool_image_transpose.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1940 Jul 25 09:04 test_tool_imagepol.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        813 Jul 25 09:04 test_tool_linearmosaic.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        642 Jul 25 09:04 test_tool_logsink.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        523 Jul 25 09:04 test_tool_measures.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       5198 Jul 25 09:04 test_tool_ms.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        341 Jul 25 09:04 test_tool_ms_createmultims.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2069 Jul 25 09:04 test_tool_ms_statwt.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       8486 Jul 25 09:04 test_tool_msmetadata.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        333 Jul 25 09:04 test_tool_msuvbinner.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1145 Jul 25 09:04 test_tool_quanta.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       3951 Jul 25 09:04 test_tool_regionmanager.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2047 Jul 25 09:04 test_tool_sdm.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2073 Jul 25 09:04 test_tool_sdm_toms.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        422 Jul 25 09:04 test_tool_simulator.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1337 Jul 25 09:04 test_tool_table.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1691 Jul 25 09:04 test_tool_vpmanager.xml
25-Jul-2023 05:04:46 Finished task 'Test wheel' with result: Success
25-Jul-2023 05:04:46 Starting task 'Tar work directory' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
25-Jul-2023 05:04:46
Beginning to execute external process for build 'CASA - CMake CI Build - CAS-14023 - Test Tools Many Linux 2.28 #2 (CASA-CMAKECI50-TTOML228-2)'
... running command line:
/export/home/cbt-el7-11/bamboohome/temp/CASA-CMAKECI50-TTOML228-2-ScriptBuildTask-1333704077263382351.sh
... in: /export/home/cbt-el7-11/bamboohome/xml-data/build-dir/CASA-CMAKECI50-TTOML228
25-Jul-2023 05:04:46 Package working directory
25-Jul-2023 05:04:46 tar: work: Cannot stat: No such file or directory
25-Jul-2023 05:04:46 tar: Exiting with failure status due to previous errors
25-Jul-2023 05:04:46 total 184628
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci 15001    112234 Jul 25 08:43 casatestutils-6.6.0.3a14023.dev1+dirty-py3-none-any.whl
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci 15001 188774174 Jul 25 08:43 casatools-6.6.0.3a14023.dev1+dirty-cp38-cp38-manylinux_2_28_x86_64.whl
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       4314 Jul 25 09:04 test_tool_agentflagger.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        980 Jul 25 09:04 test_tool_calanalysis.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2632 Jul 25 09:04 test_tool_calibrater.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        545 Jul 25 09:04 test_tool_componentlist.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       7441 Jul 25 09:04 test_tool_coordsys.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root      12706 Jul 25 09:04 test_tool_image.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2194 Jul 25 09:04 test_tool_image_collapse.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1022 Jul 25 09:04 test_tool_image_deviation.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2550 Jul 25 09:04 test_tool_image_fitprofile.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1152 Jul 25 09:04 test_tool_image_pbcor.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        857 Jul 25 09:04 test_tool_image_pv.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        980 Jul 25 09:04 test_tool_image_rebin.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1310 Jul 25 09:04 test_tool_image_regrid.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1507 Jul 25 09:04 test_tool_image_statistics.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        739 Jul 25 09:04 test_tool_image_subimage.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        864 Jul 25 09:04 test_tool_image_transpose.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1940 Jul 25 09:04 test_tool_imagepol.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        813 Jul 25 09:04 test_tool_linearmosaic.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        642 Jul 25 09:04 test_tool_logsink.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        523 Jul 25 09:04 test_tool_measures.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       5198 Jul 25 09:04 test_tool_ms.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        341 Jul 25 09:04 test_tool_ms_createmultims.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2069 Jul 25 09:04 test_tool_ms_statwt.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       8486 Jul 25 09:04 test_tool_msmetadata.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        333 Jul 25 09:04 test_tool_msuvbinner.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1145 Jul 25 09:04 test_tool_quanta.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       3951 Jul 25 09:04 test_tool_regionmanager.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2047 Jul 25 09:04 test_tool_sdm.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       2073 Jul 25 09:04 test_tool_sdm_toms.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root        422 Jul 25 09:04 test_tool_simulator.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1337 Jul 25 09:04 test_tool_table.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root       1691 Jul 25 09:04 test_tool_vpmanager.xml
25-Jul-2023 05:04:46 -rw-r--r-- 1 casaci root         45 Jul 25 09:04 work-el7-3.8.tar.gz
25-Jul-2023 05:04:46 Done packaging working directory
25-Jul-2023 05:04:46 Finished task 'Tar work directory' with result: Success
25-Jul-2023 05:04:46 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
25-Jul-2023 05:04:46 Parsing test results under /export/home/cbt-el7-11/bamboohome/xml-data/build-dir/CASA-CMAKECI50-TTOML228...
25-Jul-2023 05:04:46 Finished task 'Parse tests' with result: Success
25-Jul-2023 05:04:46 Skipping execution of task 'Upload wheel' as it is disabled
25-Jul-2023 05:04:46 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
25-Jul-2023 05:04:46
Beginning to execute external process for build 'CASA - CMake CI Build - CAS-14023 - Test Tools Many Linux 2.28 #2 (CASA-CMAKECI50-TTOML228-2)'
... running command line:
/export/home/cbt-el7-11/bamboohome/temp/CASA-CMAKECI50-TTOML228-2-ScriptBuildTask-4448597023568153907.sh
... in: /export/home/cbt-el7-11/bamboohome/xml-data/build-dir/CASA-CMAKECI50-TTOML228
25-Jul-2023 05:04:46 Starting cleanup
25-Jul-2023 05:05:00 wheel-container-test
25-Jul-2023 05:05:02 wheel-container-test
25-Jul-2023 05:05:02 Cleanup complete
25-Jul-2023 05:05:02 Finished task 'Cleanup' with result: Success
25-Jul-2023 05:05:02 Running post build plugin 'Docker Container Cleanup'
25-Jul-2023 05:05:02
Beginning to execute external process for build 'CASA - CMake CI Build - CAS-14023 - Test Tools Many Linux 2.28 #2 (CASA-CMAKECI50-TTOML228-2)'
... running command line:
/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-11/bamboohome/xml-data/build-dir/CASA-CMAKECI50-TTOML228
25-Jul-2023 05:05:02 Error response from daemon: No such container: wheel-container-test
25-Jul-2023 05:05:02 Running post build plugin 'NCover Results Collector'
25-Jul-2023 05:05:02 Running post build plugin 'Clover Results Collector'
25-Jul-2023 05:05:02 Running post build plugin 'npm Cache Cleanup'
25-Jul-2023 05:05:02 Running post build plugin 'Artifact Copier'
25-Jul-2023 05:05:02 Publishing an artifact: Workdir
25-Jul-2023 05:05:03 Finished publishing of artifact Non required job artifact: [Workdir], pattern: [work*.tar.gz] anchored at: [wheeldirectory/] in 76.95 ms
25-Jul-2023 05:05:03 Publishing an artifact: Tests
25-Jul-2023 05:05:03 Finished publishing of artifact Non required job artifact: [Tests], pattern: [*.xml] anchored at: [wheeldirectory/] in 181.1 ms
25-Jul-2023 05:05:03 Publishing an artifact: xml Directories
25-Jul-2023 05:05:03 Finished publishing of artifact Non required job artifact: [xml Directories], pattern: [**/*.xml] anchored at: [wheeldirectory/] in 250.6 ms
25-Jul-2023 05:05:03 Finalising the build...
25-Jul-2023 05:05:03 Stopping timer.
25-Jul-2023 05:05:03 Build CASA-CMAKECI50-TTOML228-2 completed.
25-Jul-2023 05:05:03 Running on server: post build plugin 'NCover Results Collector'
25-Jul-2023 05:05:03 Running on server: post build plugin 'Build Hanging Detection Configuration'
25-Jul-2023 05:05:03 Running on server: post build plugin 'Clover Delta Calculator'
25-Jul-2023 05:05:03 Running on server: post build plugin 'Maven Dependencies Postprocessor'
25-Jul-2023 05:05:03 All post build plugins have finished
25-Jul-2023 05:05:03 Generating build results summary...
25-Jul-2023 05:05:03 Saving build results to disk...
25-Jul-2023 05:05:03 Store variable context...
25-Jul-2023 05:05:03 Indexing build results...
25-Jul-2023 05:05:03 Finished building CASA-CMAKECI50-TTOML228-2.