Build: #6 was successful

Job: Test Tasks Many Linux 2.28 Py3.10 was successful

Build log

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

02-Dec-2024 21:13:53 -5.71903779426735e-07 % 6.367919683061561e-06 %
02-Dec-2024 21:13:53
02-Dec-2024 21:13:53 pol0 & pol1 ch 125: diff between 1/SIGMA**2 X 1/(FPARAM[125])**2 and WEIGHT_SPECTRUM[125] 8.146243521878205e-05 0.00020233114491929882
02-Dec-2024 21:13:53 4.334441717664649e-06 % 8.723969173137133e-06 %
02-Dec-2024 21:13:53
02-Dec-2024 21:13:53 pol0 & pol1 ch 126: diff between 1/SIGMA**2 X 1/(FPARAM[126])**2 and WEIGHT_SPECTRUM[126] 8.631705122752464e-05 -0.00016473925961690838
02-Dec-2024 21:13:53 5.48234449028032e-06 % -7.850287451233031e-06 %
02-Dec-2024 21:13:53
02-Dec-2024 21:13:53 pol0 & pol1 ch 127: diff between 1/SIGMA**2 X 1/(FPARAM[127])**2 and WEIGHT_SPECTRUM[127] 4.008430755675363e-05 9.41018111006997e-05
02-Dec-2024 21:13:53 2.789929184392109e-06 % 4.837875533460108e-06 %
02-Dec-2024 21:13:53 PASSED
02-Dec-2024 21:13:53
02-Dec-2024 21:13:53 test_task_sdcal.py::sdcal_test_ps::test_ps00
02-Dec-2024 21:13:53 test_ps00 --- default parameters (raises an error).
02-Dec-2024 21:13:53 PASSED
02-Dec-2024 21:13:53
02-Dec-2024 21:13:53 test_task_sdcal.py::sdcal_test_ps::test_ps01
02-Dec-2024 21:13:53 test_ps01 --- invalid calibration type.
02-Dec-2024 21:13:53 PASSED
02-Dec-2024 21:13:53
02-Dec-2024 21:13:53 test_task_sdcal.py::sdcal_test_ps::test_ps02 2024-12-03 02:13:54        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::Calibrater::selectvis (file /source/casa6/casatools/src/code/synthesis/MeasurementComponents/Calibrater.cc, line 4215)        Caught exception: Spw Expression: No match found for 99,
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::        Exception Reported: Error in data selection specification: Spw Expression: No match found for 99,
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 46, in sdcal
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa+            cb.selectvis(spw=spw, scan=scan, field=field)
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/calibrater.py", line 82, in selectvis
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa+            return self._swigobj.selectvis(time, spw, scan, field, intent, observation, baseline, uvrange, chanmode, nchan, start, step, mstart, mstep, msselect)
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/__casac__/calibrater.py", line 264, in selectvis
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa+            return _calibrater.calibrater_selectvis(self, *args, **kwargs)
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa+        RuntimeError: Error in data selection specification: Spw Expression: No match found for 99,
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa        Error in data selection specification: Spw Expression: No match found for 99,
02-Dec-2024 21:13:53 2024-12-03 02:13:54        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in data selection specification: Spw Expression: No match found for 99,
02-Dec-2024 21:13:53
02-Dec-2024 21:13:53 test_ps02 --- invalid selection (invalid spw selection).
02-Dec-2024 21:13:53 PASSED
02-Dec-2024 21:13:53
02-Dec-2024 21:13:54 test_task_sdcal.py::sdcal_test_ps::test_ps03 2024-12-03 02:13:54        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:13:54 2024-12-03 02:13:54        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:13:54 2024-12-03 02:13:54        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 58, in sdcal
02-Dec-2024 21:13:54 2024-12-03 02:13:54        SEVERE        sdcal::::casa+            raise RuntimeError("overwrite is False and output file exists: {}".format(outfile))
02-Dec-2024 21:13:54 2024-12-03 02:13:54        SEVERE        sdcal::::casa+        RuntimeError: overwrite is False and output file exists: uid___A002_X6218fb_X264.ms.sel.sky
02-Dec-2024 21:13:54 2024-12-03 02:13:54        SEVERE        sdcal::::casa        overwrite is False and output file exists: uid___A002_X6218fb_X264.ms.sel.sky
02-Dec-2024 21:13:54 2024-12-03 02:13:54        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: overwrite is False and output file exists: uid___A002_X6218fb_X264.ms.sel.sky
02-Dec-2024 21:13:54
02-Dec-2024 21:13:54 test_ps03 --- outfile exists (overwrite=False).
02-Dec-2024 21:13:54 PASSED
02-Dec-2024 21:13:54
02-Dec-2024 21:13:54 test_task_sdcal.py::sdcal_test_ps::test_ps04 2024-12-03 02:13:55        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:13:54 2024-12-03 02:13:55        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:13:54 2024-12-03 02:13:55        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 144, in sdcal
02-Dec-2024 21:13:54 2024-12-03 02:13:55        SEVERE        sdcal::::casa+            raise RuntimeError('Output file name must be specified.')
02-Dec-2024 21:13:54 2024-12-03 02:13:55        SEVERE        sdcal::::casa+        RuntimeError: Output file name must be specified.
02-Dec-2024 21:13:54 2024-12-03 02:13:55        SEVERE        sdcal::::casa        Output file name must be specified.
02-Dec-2024 21:13:54 2024-12-03 02:13:55        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Output file name must be specified.
02-Dec-2024 21:13:54
02-Dec-2024 21:13:54 test_ps04 --- empty outfile.
02-Dec-2024 21:13:54 PASSED
02-Dec-2024 21:13:54
02-Dec-2024 21:13:54 test_task_sdcal.py::sdcal_test_ps::test_ps05 2024-12-03 02:13:55        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:13:55
02-Dec-2024 21:13:55 test_ps05 --- position switch calibration ('ps').
02-Dec-2024 21:13:55 PASSED
02-Dec-2024 21:13:55
02-Dec-2024 21:13:55 test_task_sdcal.py::sdcal_test_ps::test_ps05M 2024-12-03 02:13:55        WARN        ParallelDataHelper::getPartitionMap::casa        Number of subMSs (8) is greater than available scan,ddi pairs (4), setting nsubms to 4
02-Dec-2024 21:13:59 2024-12-03 02:14:00        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:00
02-Dec-2024 21:14:00 test_ps05M --- position switch calibration ('ps') for MMS.
02-Dec-2024 21:14:00 PASSED
02-Dec-2024 21:14:00
02-Dec-2024 21:14:00 test_task_sdcal.py::sdcal_test_ps::test_ps06 2024-12-03 02:14:00        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:00
02-Dec-2024 21:14:00 test_ps06 --- position switch calibration ('ps') with data selection.
02-Dec-2024 21:14:00 PASSED
02-Dec-2024 21:14:00
02-Dec-2024 21:14:00 test_task_sdcal.py::sdcal_test_ps::test_ps07 2024-12-03 02:14:01        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:01
02-Dec-2024 21:14:01 test_ps07 --- outfile exists (overwrite=True).
02-Dec-2024 21:14:01 PASSED
02-Dec-2024 21:14:01
02-Dec-2024 21:14:01 test_task_sdcal.py::sdcal_test_ps::test_ps08 2024-12-03 02:14:01        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::Calibrater::solve        Caught exception: Too many edge points (2 out of 1 points)
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::        Exception Reported: Error in Calibrater::solve.
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 157, in sdcal
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa+            cb.solve()
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/calibrater.py", line 485, in solve
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa+            return self._swigobj.solve()
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/__casac__/calibrater.py", line 1133, in solve
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa+            return _calibrater.calibrater_solve(self)
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa+        RuntimeError: Error in Calibrater::solve.
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa        Error in Calibrater::solve.
02-Dec-2024 21:14:01 2024-12-03 02:14:01        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in Calibrater::solve.
02-Dec-2024 21:14:01
02-Dec-2024 21:14:01 test_ps08 --- inappropriate calmode ('otfraster').
02-Dec-2024 21:14:01 PASSED
02-Dec-2024 21:14:01
02-Dec-2024 21:14:02 test_task_sdcal.py::sdcal_test_bug_fix_cas_12712::test_cas_12712_01 2024-12-03 02:14:03        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:02
02-Dec-2024 21:14:02 PASSED
02-Dec-2024 21:14:02
02-Dec-2024 21:14:04 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster00 2024-12-03 02:14:04        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::sdcal::casa        could not convert string to float: 'auto'
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 187, in to_numeric_fraction
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+            fraction_numeric = float(fraction)
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+        ValueError: could not convert string to float: 'auto'
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+        
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+        During handling of the above exception, another exception occurred:
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+        
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+        Traceback (most recent call last):
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 149, in sdcal
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+            fraction_numeric = to_numeric_fraction(fraction)
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 192, in to_numeric_fraction
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+            raise RuntimeError('Invalid fraction value (original error message: "%s")' % (str(e)))
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+        RuntimeError: Invalid fraction value (original error message: "could not convert string to float: 'auto'")
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa        Invalid fraction value (original error message: "could not convert string to float: 'auto'")
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Invalid fraction value (original error message: "could not convert string to float: 'auto'")
02-Dec-2024 21:14:04
02-Dec-2024 21:14:04 PASSED
02-Dec-2024 21:14:04
02-Dec-2024 21:14:04 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster01 2024-12-03 02:14:04        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 151, in sdcal
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+            raise ValueError('Too many edge points. fraction must be < 0.5.')
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa+        ValueError: Too many edge points. fraction must be < 0.5.
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa        Too many edge points. fraction must be < 0.5.
02-Dec-2024 21:14:04 2024-12-03 02:14:04        SEVERE        sdcal::::casa        Task sdcal raised an exception of class ValueError with the following message: Too many edge points. fraction must be < 0.5.
02-Dec-2024 21:14:04
02-Dec-2024 21:14:04 test_otfraster01 --- too many edge points (fraction 0.5).
02-Dec-2024 21:14:04 PASSED
02-Dec-2024 21:14:04
02-Dec-2024 21:14:04 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster02 2024-12-03 02:14:04        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 151, in sdcal
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+            raise ValueError('Too many edge points. fraction must be < 0.5.')
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+        ValueError: Too many edge points. fraction must be < 0.5.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa        Too many edge points. fraction must be < 0.5.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa        Task sdcal raised an exception of class ValueError with the following message: Too many edge points. fraction must be < 0.5.
02-Dec-2024 21:14:04
02-Dec-2024 21:14:04 test_otfraster02 --- too many edge points (fraction 50%).
02-Dec-2024 21:14:04 PASSED
02-Dec-2024 21:14:04
02-Dec-2024 21:14:04 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster03 2024-12-03 02:14:05        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::Calibrater::solve        Caught exception: Too many edge points (20000 out of 9 points)
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::        Exception Reported: Error in Calibrater::solve.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 157, in sdcal
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+            cb.solve()
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/calibrater.py", line 485, in solve
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+            return self._swigobj.solve()
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/__casac__/calibrater.py", line 1133, in solve
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+            return _calibrater.calibrater_solve(self)
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+        RuntimeError: Error in Calibrater::solve.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa        Error in Calibrater::solve.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in Calibrater::solve.
02-Dec-2024 21:14:04
02-Dec-2024 21:14:04 test_otfraster03 --- too many edge points (noff 100000).
02-Dec-2024 21:14:04 PASSED
02-Dec-2024 21:14:04
02-Dec-2024 21:14:04 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster06 2024-12-03 02:14:05        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::Calibrater::solve        Caught exception: No reference integration found in user-selected data. Please double-check your data selection criteria.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::        Exception Reported: Error in Calibrater::solve.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 157, in sdcal
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+            cb.solve()
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/calibrater.py", line 485, in solve
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+            return self._swigobj.solve()
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/__casac__/calibrater.py", line 1133, in solve
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+            return _calibrater.calibrater_solve(self)
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa+        RuntimeError: Error in Calibrater::solve.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa        Error in Calibrater::solve.
02-Dec-2024 21:14:04 2024-12-03 02:14:05        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in Calibrater::solve.
02-Dec-2024 21:14:05
02-Dec-2024 21:14:05 test_otfraster06 --- inappropriate calibration mode ('ps').
02-Dec-2024 21:14:05 PASSED
02-Dec-2024 21:14:05
02-Dec-2024 21:14:05 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster07 2024-12-03 02:14:05        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:07
02-Dec-2024 21:14:07 test_otfraster07 --- OTF raster calibration ('otfraster') with default setting.
02-Dec-2024 21:14:07 PASSED
02-Dec-2024 21:14:07
02-Dec-2024 21:14:07 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster07M 2024-12-03 02:14:08        WARN        ParallelDataHelper::getPartitionMap::casa        Number of subMSs (8) is greater than available scan,ddi pairs (4), setting nsubms to 4
02-Dec-2024 21:14:12 2024-12-03 02:14:12        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:14
02-Dec-2024 21:14:14 test_otfraster07M --- OTF raster calibration ('otfraster') with default setting (MMS).
02-Dec-2024 21:14:14 PASSED
02-Dec-2024 21:14:14
02-Dec-2024 21:14:14 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster08 2024-12-03 02:14:15        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:17
02-Dec-2024 21:14:17 test_otfraster08 --- OTF raster calibration ('otfraster') with string fraction (numeric value).
02-Dec-2024 21:14:17 PASSED
02-Dec-2024 21:14:17
02-Dec-2024 21:14:17 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster09 2024-12-03 02:14:18        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:20
02-Dec-2024 21:14:20 test_otfraster09 --- OTF raster calibration ('otfraster') with string fraction (percentage).
02-Dec-2024 21:14:20 PASSED
02-Dec-2024 21:14:20
02-Dec-2024 21:14:20 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster10 2024-12-03 02:14:21        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:23
02-Dec-2024 21:14:23 test_otfraster10 --- OTF raster calibration ('otfraster') with numeric fraction.
02-Dec-2024 21:14:23 PASSED
02-Dec-2024 21:14:23
02-Dec-2024 21:14:23 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster11 2024-12-03 02:14:23        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:25
02-Dec-2024 21:14:25 test_otfraster11 --- OTF raster calibration ('otfraster') with auto detection.
02-Dec-2024 21:14:25 PASSED
02-Dec-2024 21:14:25
02-Dec-2024 21:14:26 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster12 2024-12-03 02:14:26        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:28
02-Dec-2024 21:14:28 test_otfraster12 --- OTF raster calibration ('otfraster') with custom noff.
02-Dec-2024 21:14:28 PASSED
02-Dec-2024 21:14:28
02-Dec-2024 21:14:28 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster13 2024-12-03 02:14:29        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:31
02-Dec-2024 21:14:31 test_otfraster13 --- check if noff takes priority over fraction.
02-Dec-2024 21:14:31 PASSED
02-Dec-2024 21:14:31
02-Dec-2024 21:14:32 test_task_sdcal.py::sdcal_test_otf::test_otf01 2024-12-03 02:14:32        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:32
02-Dec-2024 21:14:32 test_otf01 --- Compute calibration table. calmode='otf' ms=squares.dec60_cs.ms.
02-Dec-2024 21:14:32 PASSED
02-Dec-2024 21:14:32
02-Dec-2024 21:14:32 test_task_sdcal.py::sdcal_test_otf::test_otf02 2024-12-03 02:14:32        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:32
02-Dec-2024 21:14:32 test_otf02 --- Compute calibration table. calmode='otf' ms=squares.dec60_cs.ms edges_fraction=20%.
02-Dec-2024 21:14:32 PASSED
02-Dec-2024 21:14:32
02-Dec-2024 21:14:32 test_task_sdcal.py::sdcal_test_otf::test_otf03 2024-12-03 02:14:33        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:33
02-Dec-2024 21:14:33 test_otf03 --- Compute calibration table. calmode='otf' ms=lissajous.ms.
02-Dec-2024 21:14:33 PASSED
02-Dec-2024 21:14:33
02-Dec-2024 21:14:33 test_task_sdcal.py::sdcal_test_otf::test_otf03M 2024-12-03 02:14:33        WARN        ParallelDataHelper::getPartitionMap::casa        Number of subMSs (8) is greater than available scan,ddi pairs (4), setting nsubms to 4
02-Dec-2024 21:14:36 2024-12-03 02:14:36        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:36
02-Dec-2024 21:14:36 test_otf03 --- Compute calibration table. calmode='otf' ms=lissajous.ms (MMS).
02-Dec-2024 21:14:36 PASSED
02-Dec-2024 21:14:36
02-Dec-2024 21:14:36 test_task_sdcal.py::sdcal_test_otf::test_otf04 2024-12-03 02:14:37        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:36
02-Dec-2024 21:14:36 test_otf04 --- Compute calibration table. calmode='otf' ms=lissajous.ms edges_fraction=20%.
02-Dec-2024 21:14:36 PASSED
02-Dec-2024 21:14:36
02-Dec-2024 21:14:36 test_task_sdcal.py::sdcal_test_otf::test_otf05 2024-12-03 02:14:37        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:37 The following MS spws have no corresponding cal spws in squares.dec60_cs.edges_fraction_0.1.ms_caltable: 0 1 2 3
02-Dec-2024 21:14:37 2024-12-03 02:14:37        WARN        sdcal::CTPatchedInterp::interpolate        In caltable squares.dec60_cs.edges_fraction_0.1.ms_caltable (SDSKY_OTF):
02-Dec-2024 21:14:37 2024-12-03 02:14:37        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
02-Dec-2024 21:14:37 2024-12-03 02:14:37        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
02-Dec-2024 21:14:37 2024-12-03 02:14:37        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:37 2024-12-03 02:14:37        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:37 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_otf_020.tab: 0 1 2 3
02-Dec-2024 21:14:37 2024-12-03 02:14:37        WARN        sdcal::CTPatchedInterp::interpolate        In caltable __sdcal_composite_mode_otf_020.tab (SDSKY_OTF):
02-Dec-2024 21:14:37 2024-12-03 02:14:37        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
02-Dec-2024 21:14:37 2024-12-03 02:14:37        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
02-Dec-2024 21:14:37
02-Dec-2024 21:14:37 test_otf05 --- Sky calibration. calmode='otf,apply' ms=squares.dec60_cs.ms.
02-Dec-2024 21:14:37 PASSED
02-Dec-2024 21:14:37
02-Dec-2024 21:14:37 test_task_sdcal.py::sdcal_test_otf::test_otf06 2024-12-03 02:14:38        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:37 The following MS spws have no corresponding cal spws in lissajous.edges_new_fraction_0.1.ms_caltable: 0 1 2 3
02-Dec-2024 21:14:37 2024-12-03 02:14:38        WARN        sdcal::CTPatchedInterp::interpolate        In caltable lissajous.edges_new_fraction_0.1.ms_caltable (SDSKY_OTF):
02-Dec-2024 21:14:37 2024-12-03 02:14:38        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
02-Dec-2024 21:14:37 2024-12-03 02:14:38        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
02-Dec-2024 21:14:38
02-Dec-2024 21:14:38 test_otf06 --- Sky calibration reusing caltable pre-computed with calmode='otf'. calmode='apply' ms=lissajous.ms.
02-Dec-2024 21:14:38 PASSED
02-Dec-2024 21:14:38
02-Dec-2024 21:14:38 test_task_sdcal.py::sdcal_test_otf::test_otf07 2024-12-03 02:14:38        WARN        sdcal::::casa        Intent selection for 'otf' or 'otfraster' should be 'OBSERVE_TARGET#ON_SOURCE'.
02-Dec-2024 21:14:38 2024-12-03 02:14:38        WARN        sdcal::::casa+        However, the task is not allowed to set global intent selection since calmode contains 'tsys'.
02-Dec-2024 21:14:38 2024-12-03 02:14:38        WARN        sdcal::::casa+        As a workaround, set intent selection locally when 'otf' or 'otfraster' calibration is performed.
02-Dec-2024 21:14:38 2024-12-03 02:14:38        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:38 2024-12-03 02:14:39        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:38 2024-12-03 02:14:39        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:38 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_otf_038.tab: 0 1 2 3
02-Dec-2024 21:14:38 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_tsys_021.tab: 0 1 2 3
02-Dec-2024 21:14:38 2024-12-03 02:14:39        WARN        sdcal::CTPatchedInterp::interpolate        In caltable __sdcal_composite_mode_otf_038.tab (SDSKY_OTF):
02-Dec-2024 21:14:38 2024-12-03 02:14:39        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
02-Dec-2024 21:14:38 2024-12-03 02:14:39        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
02-Dec-2024 21:14:38 2024-12-03 02:14:39        WARN        sdcal::CTPatchedInterp::interpolate        In caltable __sdcal_composite_mode_tsys_021.tab (B TSYS):
02-Dec-2024 21:14:38 2024-12-03 02:14:39        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
02-Dec-2024 21:14:38 2024-12-03 02:14:39        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
02-Dec-2024 21:14:39
02-Dec-2024 21:14:39 test_otf07 --- Sky calibration + Tsys conversion, composite calmode='otf,tsys,apply'. ms=lissajous.ms.
02-Dec-2024 21:14:39 PASSED
02-Dec-2024 21:14:39
02-Dec-2024 21:14:39 test_task_sdcal.py::sdcal_test_otf_ephem::test_otfephem01 2024-12-03 02:14:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:40
02-Dec-2024 21:14:40 test_otfephem01: Sky calibration of 'otf' mode for ephemeris object.
02-Dec-2024 21:14:40 PASSED
02-Dec-2024 21:14:40
02-Dec-2024 21:14:40 test_task_sdcal.py::sdcal_test_otf_ephem::test_otfephem02 2024-12-03 02:14:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:40 2024-12-03 02:14:41        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:40 2024-12-03 02:14:41        WARN        sdcal::CTPatchedInterp::interpolate        In caltable __sdcal_composite_mode_otf_029.tab (SDSKY_OTF):
02-Dec-2024 21:14:40 2024-12-03 02:14:41        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
02-Dec-2024 21:14:40 2024-12-03 02:14:41        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=0; using NEAREST instead.
02-Dec-2024 21:14:40 2024-12-03 02:14:41        WARN        sdcal::::        MS obs=0,spw=0,ant=1 is selected for processing, but has no available calibration in __sdcal_composite_mode_otf_029.tab as mapped, and will be flagged.
02-Dec-2024 21:14:40
02-Dec-2024 21:14:40 test_otfephem02: On-the-fly application of 'otf' calibration mode for ephemeris object.
02-Dec-2024 21:14:40 PASSED
02-Dec-2024 21:14:40
02-Dec-2024 21:14:41 test_task_sdcal.py::sdcal_test_apply::test_apply_composite00 2024-12-03 02:14:41        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:41 2024-12-03 02:14:41        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:41 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_ps_045.tab: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:41 2024-12-03 02:14:41        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_045.tab as mapped, and will be flagged.
02-Dec-2024 21:14:41 2024-12-03 02:14:41        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_045.tab as mapped, and will be flagged.
02-Dec-2024 21:14:41 2024-12-03 02:14:42        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_045.tab as mapped, and will be flagged.
02-Dec-2024 21:14:41 2024-12-03 02:14:42        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_045.tab as mapped, and will be flagged.
02-Dec-2024 21:14:41 2024-12-03 02:14:42        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:41 2024-12-03 02:14:42        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:41 2024-12-03 02:14:42        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:41 2024-12-03 02:14:42        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:41
02-Dec-2024 21:14:41 test_apply_composite00 --- on-the-fly application of sky table ('ps,apply').
02-Dec-2024 21:14:41 Interpolation option: linear nearestflag
02-Dec-2024 21:14:41 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:41 PASSED
02-Dec-2024 21:14:41
02-Dec-2024 21:14:41 test_task_sdcal.py::sdcal_test_apply::test_apply_composite01 2024-12-03 02:14:42        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:42 2024-12-03 02:14:42        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:42 2024-12-03 02:14:42        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:42 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.tsys: 0 2 4 6 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:42 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_ps_097.tab: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:42 2024-12-03 02:14:43        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_097.tab as mapped, and will be flagged.
02-Dec-2024 21:14:42 2024-12-03 02:14:43        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_097.tab as mapped, and will be flagged.
02-Dec-2024 21:14:42 2024-12-03 02:14:43        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_097.tab as mapped, and will be flagged.
02-Dec-2024 21:14:42 2024-12-03 02:14:43        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_097.tab as mapped, and will be flagged.
02-Dec-2024 21:14:42 2024-12-03 02:14:43        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:42 2024-12-03 02:14:43        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:42 2024-12-03 02:14:43        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:42 2024-12-03 02:14:43        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:42
02-Dec-2024 21:14:42 test_apply_composite01 --- on-the-fly application of sky table with existing Tsys table.
02-Dec-2024 21:14:42 Interpolation option: linear nearestflag
02-Dec-2024 21:14:42 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:42 PASSED
02-Dec-2024 21:14:42
02-Dec-2024 21:14:43 test_task_sdcal.py::sdcal_test_apply::test_apply_composite02 2024-12-03 02:14:43        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:43 2024-12-03 02:14:43        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:43 2024-12-03 02:14:43        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:43 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_ps_074.tab: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:43 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_tsys_031.tab: 0 2 4 6 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:43 2024-12-03 02:14:44        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_074.tab as mapped, and will be flagged.
02-Dec-2024 21:14:43 2024-12-03 02:14:44        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_074.tab as mapped, and will be flagged.
02-Dec-2024 21:14:43 2024-12-03 02:14:44        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_074.tab as mapped, and will be flagged.
02-Dec-2024 21:14:43 2024-12-03 02:14:44        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_074.tab as mapped, and will be flagged.
02-Dec-2024 21:14:43 2024-12-03 02:14:44        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:43 2024-12-03 02:14:44        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:43 2024-12-03 02:14:44        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:43 2024-12-03 02:14:44        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:43
02-Dec-2024 21:14:43 test_apply_composite02 --- on-the-fly application of sky and tsys tables ('ps,tsys,apply').
02-Dec-2024 21:14:43 Interpolation option: linear nearestflag
02-Dec-2024 21:14:43 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:43 PASSED
02-Dec-2024 21:14:43
02-Dec-2024 21:14:44 test_task_sdcal.py::sdcal_test_apply::test_apply_composite03 2024-12-03 02:14:44        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:44 2024-12-03 02:14:44        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:44 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:44 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_tsys_041.tab: 0 2 4 6 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:44 2024-12-03 02:14:45        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:44 2024-12-03 02:14:45        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:44 2024-12-03 02:14:45        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:44 2024-12-03 02:14:45        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:44 2024-12-03 02:14:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:44 2024-12-03 02:14:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:44 2024-12-03 02:14:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:44 2024-12-03 02:14:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:44
02-Dec-2024 21:14:44 test_apply_composite03 --- on-the-fly application of sky table ('otfraster,apply').
02-Dec-2024 21:14:44 Interpolation option: linear nearestflag
02-Dec-2024 21:14:44 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:44 PASSED
02-Dec-2024 21:14:44
02-Dec-2024 21:14:45 test_task_sdcal.py::sdcal_test_apply::test_apply_sky00 2024-12-03 02:14:45        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:45 2024-12-03 02:14:45        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:45 2024-12-03 02:14:45        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 78, in sdcal
02-Dec-2024 21:14:45 2024-12-03 02:14:45        SEVERE        sdcal::::casa+            raise Exception('Applytable name must be specified.')
02-Dec-2024 21:14:45 2024-12-03 02:14:45        SEVERE        sdcal::::casa+        Exception: Applytable name must be specified.
02-Dec-2024 21:14:45 2024-12-03 02:14:45        SEVERE        sdcal::::casa        Applytable name must be specified.
02-Dec-2024 21:14:45 2024-12-03 02:14:45        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Applytable name must be specified.
02-Dec-2024 21:14:45
02-Dec-2024 21:14:45 test_apply_sky00 --- empty applytable.
02-Dec-2024 21:14:45 PASSED
02-Dec-2024 21:14:45
02-Dec-2024 21:14:45 test_task_sdcal.py::sdcal_test_apply::test_apply_sky01 2024-12-03 02:14:46        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:45 2024-12-03 02:14:46        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:45 2024-12-03 02:14:46        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 78, in sdcal
02-Dec-2024 21:14:45 2024-12-03 02:14:46        SEVERE        sdcal::::casa+            raise Exception('Applytable name must be specified.')
02-Dec-2024 21:14:45 2024-12-03 02:14:46        SEVERE        sdcal::::casa+        Exception: Applytable name must be specified.
02-Dec-2024 21:14:45 2024-12-03 02:14:46        SEVERE        sdcal::::casa        Applytable name must be specified.
02-Dec-2024 21:14:45 2024-12-03 02:14:46        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Applytable name must be specified.
02-Dec-2024 21:14:45
02-Dec-2024 21:14:45 test_apply_sky01 --- empty applytable (list ver.).
02-Dec-2024 21:14:45 PASSED
02-Dec-2024 21:14:45
02-Dec-2024 21:14:45 test_task_sdcal.py::sdcal_test_apply::test_apply_sky02 2024-12-03 02:14:46        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:46 2024-12-03 02:14:46        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:46 2024-12-03 02:14:46        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 72, in sdcal
02-Dec-2024 21:14:46 2024-12-03 02:14:46        SEVERE        sdcal::::casa+            raise Exception('Applytable name must be specified.')
02-Dec-2024 21:14:46 2024-12-03 02:14:46        SEVERE        sdcal::::casa+        Exception: Applytable name must be specified.
02-Dec-2024 21:14:46 2024-12-03 02:14:46        SEVERE        sdcal::::casa        Applytable name must be specified.
02-Dec-2024 21:14:46 2024-12-03 02:14:46        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Applytable name must be specified.
02-Dec-2024 21:14:46
02-Dec-2024 21:14:46 test_apply_sky02 --- empty applytable list.
02-Dec-2024 21:14:46 PASSED
02-Dec-2024 21:14:46
02-Dec-2024 21:14:46 test_task_sdcal.py::sdcal_test_apply::test_apply_sky03 2024-12-03 02:14:46        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:46 2024-12-03 02:14:47        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:46 2024-12-03 02:14:47        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 81, in sdcal
02-Dec-2024 21:14:46 2024-12-03 02:14:47        SEVERE        sdcal::::casa+            raise Exception("Table doesn't exist: {}".format(_table_name))
02-Dec-2024 21:14:46 2024-12-03 02:14:47        SEVERE        sdcal::::casa+        Exception: Table doesn't exist: notexist.sky
02-Dec-2024 21:14:46 2024-12-03 02:14:47        SEVERE        sdcal::::casa        Table doesn't exist: notexist.sky
02-Dec-2024 21:14:46 2024-12-03 02:14:47        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Table doesn't exist: notexist.sky
02-Dec-2024 21:14:46
02-Dec-2024 21:14:46 test_apply_sky03 --- unexisting applytable.
02-Dec-2024 21:14:46 PASSED
02-Dec-2024 21:14:46
02-Dec-2024 21:14:46 test_task_sdcal.py::sdcal_test_apply::test_apply_sky04 2024-12-03 02:14:47        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:47 2024-12-03 02:14:47        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:47 2024-12-03 02:14:47        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 81, in sdcal
02-Dec-2024 21:14:47 2024-12-03 02:14:47        SEVERE        sdcal::::casa+            raise Exception("Table doesn't exist: {}".format(_table_name))
02-Dec-2024 21:14:47 2024-12-03 02:14:47        SEVERE        sdcal::::casa+        Exception: Table doesn't exist: notexist.sky
02-Dec-2024 21:14:47 2024-12-03 02:14:47        SEVERE        sdcal::::casa        Table doesn't exist: notexist.sky
02-Dec-2024 21:14:47 2024-12-03 02:14:47        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Table doesn't exist: notexist.sky
02-Dec-2024 21:14:47
02-Dec-2024 21:14:47 test_apply_sky04 --- unexisting applytable (list ver.).
02-Dec-2024 21:14:47 PASSED
02-Dec-2024 21:14:47
02-Dec-2024 21:14:47 test_task_sdcal.py::sdcal_test_apply::test_apply_sky05 2024-12-03 02:14:47        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::Calibrater::selectvis (file /source/casa6/casatools/src/code/synthesis/MeasurementComponents/Calibrater.cc, line 4215)        Caught exception: Spw Expression: No match found for 99,
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::        Exception Reported: Error in data selection specification: Spw Expression: No match found for 99,
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 46, in sdcal
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa+            cb.selectvis(spw=spw, scan=scan, field=field)
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/calibrater.py", line 82, in selectvis
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa+            return self._swigobj.selectvis(time, spw, scan, field, intent, observation, baseline, uvrange, chanmode, nchan, start, step, mstart, mstep, msselect)
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/__casac__/calibrater.py", line 264, in selectvis
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa+            return _calibrater.calibrater_selectvis(self, *args, **kwargs)
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa+        RuntimeError: Error in data selection specification: Spw Expression: No match found for 99,
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa        Error in data selection specification: Spw Expression: No match found for 99,
02-Dec-2024 21:14:47 2024-12-03 02:14:48        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in data selection specification: Spw Expression: No match found for 99,
02-Dec-2024 21:14:47
02-Dec-2024 21:14:47 test_apply_sky05 --- invalid selection (empty selection result).
02-Dec-2024 21:14:47 PASSED
02-Dec-2024 21:14:47
02-Dec-2024 21:14:47 test_task_sdcal.py::sdcal_test_apply::test_apply_sky06 2024-12-03 02:14:48        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::Calibrater::setapply(type, applypar)        Unknown interp type: 'sinusoid'!! Check inputs and try again.
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::        Exception Reported: Error in Calibrater::setapply.
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 121, in sdcal
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+            cb.setapply(table=_table, interp=interp, calwt=True)
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/calibrater.py", line 212, in setapply
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+            return self._swigobj.setapply(type, t, table, field, interp, select, calwt, spwmap, opacity)
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatools/__casac__/calibrater.py", line 479, in setapply
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+            return _calibrater.calibrater_setapply(self, *args, **kwargs)
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+        RuntimeError: Error in Calibrater::setapply.
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa        Error in Calibrater::setapply.
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in Calibrater::setapply.
02-Dec-2024 21:14:48
02-Dec-2024 21:14:48 test_apply_sky06 --- invalid interp value.
02-Dec-2024 21:14:48 PASSED
02-Dec-2024 21:14:48
02-Dec-2024 21:14:48 test_task_sdcal.py::sdcal_test_apply::test_apply_sky07 2024-12-03 02:14:48        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa        Traceback (most recent call last):
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.10/site-packages/casatasks/private/task_sdcal.py", line 116, in sdcal
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+            raise RuntimeError('Applytable \'%s\' is not a caltable format' % (_table))
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa+        RuntimeError: Applytable 'uid___A002_X6218fb_X264.ms.sel' is not a caltable format
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa        Applytable 'uid___A002_X6218fb_X264.ms.sel' is not a caltable format
02-Dec-2024 21:14:48 2024-12-03 02:14:48        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Applytable 'uid___A002_X6218fb_X264.ms.sel' is not a caltable format
02-Dec-2024 21:14:48
02-Dec-2024 21:14:48 test_apply_sky07 --- invalid applytable (not caltable).
02-Dec-2024 21:14:48 PASSED
02-Dec-2024 21:14:48
02-Dec-2024 21:14:48 test_task_sdcal.py::sdcal_test_apply::test_apply_sky08 2024-12-03 02:14:49        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:49 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:49 2024-12-03 02:14:49        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:49 2024-12-03 02:14:49        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:49 2024-12-03 02:14:49        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:49 2024-12-03 02:14:49        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:49 2024-12-03 02:14:49        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:49 2024-12-03 02:14:49        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:49 2024-12-03 02:14:49        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:49 2024-12-03 02:14:49        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:49
02-Dec-2024 21:14:49 test_apply_sky08 --- apply data (linear).
02-Dec-2024 21:14:49 Interpolation option: linear nearestflag
02-Dec-2024 21:14:49 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:49 PASSED
02-Dec-2024 21:14:49
02-Dec-2024 21:14:49 test_task_sdcal.py::sdcal_test_apply::test_apply_sky08M
02-Dec-2024 21:14:49 test_apply_sky08M --- apply data (linear) for MMS.
02-Dec-2024 21:14:49 SKIPPED (Skip test_apply_sky08M until calibrator tool supports proce...)
02-Dec-2024 21:14:49
02-Dec-2024 21:14:49 test_task_sdcal.py::sdcal_test_apply::test_apply_sky09 2024-12-03 02:14:50        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:50 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:50 2024-12-03 02:14:50        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:50 2024-12-03 02:14:50        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:50
02-Dec-2024 21:14:50 test_apply_sky09 --- apply selected data.
02-Dec-2024 21:14:50 Interpolation option: linear nearestflag
02-Dec-2024 21:14:50 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:50 PASSED
02-Dec-2024 21:14:50
02-Dec-2024 21:14:50 test_task_sdcal.py::sdcal_test_apply::test_apply_sky10 2024-12-03 02:14:51        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:50 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:50 2024-12-03 02:14:51        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:50 2024-12-03 02:14:51        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:50 2024-12-03 02:14:51        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:50 2024-12-03 02:14:51        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:50 2024-12-03 02:14:51        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:50 2024-12-03 02:14:51        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:50 2024-12-03 02:14:51        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:50 2024-12-03 02:14:51        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:50
02-Dec-2024 21:14:50 test_apply_sky10 --- apply data (nearest).
02-Dec-2024 21:14:50 Interpolation option: nearest nearestflag
02-Dec-2024 21:14:50 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:50 PASSED
02-Dec-2024 21:14:50
02-Dec-2024 21:14:51 test_task_sdcal.py::sdcal_test_apply::test_apply_sky11 2024-12-03 02:14:51        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:51 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:51 2024-12-03 02:14:51        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:51 2024-12-03 02:14:51        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:51 2024-12-03 02:14:51        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:51 2024-12-03 02:14:51        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:51 2024-12-03 02:14:52        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:51 2024-12-03 02:14:52        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:51 2024-12-03 02:14:52        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:51 2024-12-03 02:14:52        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:51
02-Dec-2024 21:14:51 test_apply_sky11 --- apply data (linearflag for frequency interpolation).
02-Dec-2024 21:14:51 Interpolation option: linear nearestflag
02-Dec-2024 21:14:51 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:51 PASSED
02-Dec-2024 21:14:51
02-Dec-2024 21:14:51 test_task_sdcal.py::sdcal_test_apply::test_apply_sky12 2024-12-03 02:14:52        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:52 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:52 2024-12-03 02:14:52        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:52 2024-12-03 02:14:52        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:52 2024-12-03 02:14:52        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:52 2024-12-03 02:14:52        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:52 2024-12-03 02:14:52        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:52 2024-12-03 02:14:52        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:52 2024-12-03 02:14:52        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:52 2024-12-03 02:14:52        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:52
02-Dec-2024 21:14:52 test_apply_sky12 --- apply data (nearestflag for frequency interpolation).
02-Dec-2024 21:14:52 Interpolation option: linear nearestflag
02-Dec-2024 21:14:52 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:52 PASSED
02-Dec-2024 21:14:52
02-Dec-2024 21:14:52 test_task_sdcal.py::sdcal_test_apply::test_apply_sky13 2024-12-03 02:14:53        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:52 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:52 2024-12-03 02:14:53        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:52 2024-12-03 02:14:53        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:52 2024-12-03 02:14:53        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:52 2024-12-03 02:14:53        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:52 2024-12-03 02:14:53        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:52 2024-12-03 02:14:53        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:52 2024-12-03 02:14:53        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:52 2024-12-03 02:14:53        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:53
02-Dec-2024 21:14:53 test_apply_sky13 --- apply data (string applytable input).
02-Dec-2024 21:14:53 Interpolation option: linear nearestflag
02-Dec-2024 21:14:53 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:53 PASSED
02-Dec-2024 21:14:53
02-Dec-2024 21:14:53 test_task_sdcal.py::sdcal_test_apply::test_apply_sky14 2024-12-03 02:14:53        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:53 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:53 2024-12-03 02:14:54        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:53 2024-12-03 02:14:54        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:53 2024-12-03 02:14:54        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:53 2024-12-03 02:14:54        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:53 2024-12-03 02:14:54        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:53 2024-12-03 02:14:54        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:53 2024-12-03 02:14:54        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:53 2024-12-03 02:14:54        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:53
02-Dec-2024 21:14:53 test_apply_sky14 --- apply data (interp='').
02-Dec-2024 21:14:53 Interpolation option: linear nearestflag
02-Dec-2024 21:14:53 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:53 PASSED
02-Dec-2024 21:14:53
02-Dec-2024 21:14:54 test_task_sdcal.py::sdcal_test_apply::test_apply_sky15 2024-12-03 02:14:54        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:54 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
02-Dec-2024 21:14:54 2024-12-03 02:14:54        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:54 2024-12-03 02:14:54        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:54 2024-12-03 02:14:54        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:54 2024-12-03 02:14:54        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in uid___A002_X6218fb_X264.ms.sel.sky as mapped, and will be flagged.
02-Dec-2024 21:14:54 2024-12-03 02:14:55        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
02-Dec-2024 21:14:54 2024-12-03 02:14:55        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:54 2024-12-03 02:14:55        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:54 2024-12-03 02:14:55        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:54
02-Dec-2024 21:14:54 test_apply_sky15 --- check if WEIGHT_SPECTRUM is updated properly when it exists.
02-Dec-2024 21:14:54 Interpolation option: linear nearestflag
02-Dec-2024 21:14:54 self.finterp: interp_freq_nearestflag
02-Dec-2024 21:14:54 PASSED
02-Dec-2024 21:14:54
02-Dec-2024 21:14:55 test_task_sdcal.py::sdcal_test_single_polarization::test_single_pol_apply 2024-12-03 02:14:55        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:55 2024-12-03 02:14:55        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:55 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0
02-Dec-2024 21:14:55 2024-12-03 02:14:55        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 0,
02-Dec-2024 21:14:55 2024-12-03 02:14:55        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:55 2024-12-03 02:14:55        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:55 2024-12-03 02:14:55        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:55
02-Dec-2024 21:14:55 test_single_pol_apply --- apply caltable to single-polarization data.
02-Dec-2024 21:14:55 PASSED
02-Dec-2024 21:14:55
02-Dec-2024 21:14:55 test_task_sdcal.py::sdcal_test_single_polarization::test_single_pol_apply_composite 2024-12-03 02:14:55        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:55 2024-12-03 02:14:55        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:55 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_ps_100.tab: 0
02-Dec-2024 21:14:55 2024-12-03 02:14:56        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 0,
02-Dec-2024 21:14:55 2024-12-03 02:14:56        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
02-Dec-2024 21:14:55 2024-12-03 02:14:56        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
02-Dec-2024 21:14:55 2024-12-03 02:14:56        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
02-Dec-2024 21:14:55
02-Dec-2024 21:14:55 test_single_pol_apply_composite --- on-the-fly calibration/application on single-polarization data.
02-Dec-2024 21:14:55 PASSED
02-Dec-2024 21:14:55
02-Dec-2024 21:14:55 test_task_sdcal.py::sdcal_test_single_polarization::test_single_pol_ps 2024-12-03 02:14:56        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
02-Dec-2024 21:14:55
02-Dec-2024 21:14:55 test_single_pol_ps --- generate caltable for single-polarization data.
02-Dec-2024 21:14:55 PASSED
02-Dec-2024 21:14:55
02-Dec-2024 21:14:55 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdcal/nose.xml -
02-Dec-2024 21:14:55 =========================== short test summary info ============================
02-Dec-2024 21:14:55 SKIPPED [1] test_task_sdcal.py:1979: Skip test_apply_sky08M until calibrator tool supports processing MMS on serial casa
02-Dec-2024 21:14:55 ============ 65 passed, 1 skipped, 14 warnings in 75.82s (0:01:15) =============
02-Dec-2024 21:14:56 ============================= test session starts ==============================
02-Dec-2024 21:14:56 platform linux -- Python 3.10.4, pytest-8.3.4, pluggy-1.5.0 -- /home/casatest/venv/bin/python3.10
02-Dec-2024 21:14:56 cachedir: .pytest_cache
02-Dec-2024 21:14:56 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_task_nrobeamaverage
02-Dec-2024 21:14:56 configfile: pytest.ini
02-Dec-2024 21:14:57 collecting ... collected 5 items
02-Dec-2024 21:14:57
02-Dec-2024 21:14:57
02-Dec-2024 21:15:07 test_task_nrobeamaverage.py::test_nrobeamaverage::test_beam0
02-Dec-2024 21:15:07 PASSED
02-Dec-2024 21:15:07
02-Dec-2024 21:15:17 test_task_nrobeamaverage.py::test_nrobeamaverage::test_beam01
02-Dec-2024 21:15:17 PASSED
02-Dec-2024 21:15:17
02-Dec-2024 21:15:26 test_task_nrobeamaverage.py::test_nrobeamaverage::test_beam1
02-Dec-2024 21:15:26 PASSED
02-Dec-2024 21:15:26
02-Dec-2024 21:15:36 test_task_nrobeamaverage.py::test_nrobeamaverage::test_default
02-Dec-2024 21:15:36 PASSED
02-Dec-2024 21:15:36
02-Dec-2024 21:15:37 test_task_nrobeamaverage.py::test_nrobeamaverage::test_time_averaging
02-Dec-2024 21:15:37 PASSED
02-Dec-2024 21:15:37
02-Dec-2024 21:15:37 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_task_nrobeamaverage/nose.xml -
02-Dec-2024 21:15:37 ======================== 5 passed, 2 warnings in 41.52s ========================
02-Dec-2024 21:15:38 HAVE_PYTEST: True
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Namespace(list=False, verbose=False, dry_run=False, classes=None, file=None, mapfile=<_io.TextIOWrapper name='/home/casatest/casa6/casatestutils/casatestutils/component_to_test_map.json' mode='r' encoding='UTF-8'>, branch=None, merge_target=None, pkg=None, work_dir=None, ncores=2, test_paths='/home/casatest/casa6/casatasks/tests/tasks', test_list=None, test_config=None, test_group='sdbaseline,Single Dish', pmode=None, bamboo=False, cachedir=None, ignore_list='test_casampi,test_task_msview,test_task_imview,test_task_plotms')
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Operating system: Linux
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Testing Components['sdbaseline', 'Single Dish']
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Arguments Sent Direct To Pytest :  []
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Tests to Ignore:  ['test_casampi', 'test_task_msview', 'test_task_imview', 'test_task_plotms']
02-Dec-2024 21:15:38 Running 12 Test(s)
02-Dec-2024 21:15:38 Cleaning: /home/casatest/casa6/casatestutils/nosedir/
02-Dec-2024 21:15:38 Tests: ['/home/casatest/casa6/casatasks/tests/tasks/test_task_imbaseline.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_nrobeamaverage.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdatmcor.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdbaseline.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdcal.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdfit.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdfixscan.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdgaincal.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdpolaverage.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdsidebandsplit.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdsmooth.py', '/home/casatest/casa6/casatasks/tests/tasks/test_task_sdtimeaverage.py']
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdbaseline/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdbaseline
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdbaseline/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdbaseline/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_imbaseline/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_imbaseline
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_imbaseline/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_imbaseline/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdatmcor/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdatmcor
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdatmcor/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdatmcor/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdfixscan/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdfixscan
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdfixscan/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdfixscan/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdgaincal/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdgaincal
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdgaincal/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdgaincal/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdsmooth/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdsmooth
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdsmooth/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdsmooth/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdfit/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdfit
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdfit/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdfit/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdpolaverage/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdpolaverage
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdpolaverage/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdpolaverage/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdtimeaverage/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdtimeaverage
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdtimeaverage/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdtimeaverage/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdsidebandsplit/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdsidebandsplit
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdsidebandsplit/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdsidebandsplit/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdcal/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdcal
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdcal/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_sdcal/
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_nrobeamaverage/
02-Dec-2024 21:15:38 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_nrobeamaverage
02-Dec-2024 21:15:38
02-Dec-2024 21:15:38 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_task_nrobeamaverage/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_task_nrobeamaverage/
02-Dec-2024 21:15:39 total 180752
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci 15001   1854204 Dec  3 01:37 casatasks-6.7.0.0a13671.dev7-py3-none-any.whl
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root     951643 Dec  3 02:15 casatasks_test.log
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci 15001    129239 Dec  3 01:37 casatestutils-6.7.0.0a13671.dev7-py3-none-any.whl
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci 15001 182033659 Dec  3 01:37 casatools-6.7.0.0a13671.dev7-cp310-cp310-manylinux_2_28_x86_64.whl
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root        823 Dec  3 02:15 requirements.txt
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root      29182 Dec  3 02:15 test_task_imbaseline.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root        763 Dec  3 02:15 test_task_nrobeamaverage.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root       5020 Dec  3 02:15 test_task_sdatmcor.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root      17967 Dec  3 02:15 test_task_sdbaseline.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root       6626 Dec  3 02:15 test_task_sdcal.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root       4156 Dec  3 02:15 test_task_sdfit.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root       1841 Dec  3 02:15 test_task_sdfixscan.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root       1569 Dec  3 02:15 test_task_sdgaincal.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root        565 Dec  3 02:15 test_task_sdpolaverage.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root       2433 Dec  3 02:15 test_task_sdsidebandsplit.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root       3927 Dec  3 02:15 test_task_sdsmooth.xml
02-Dec-2024 21:15:39 -rw-r--r-- 1 casaci root       4379 Dec  3 02:15 test_task_sdtimeaverage.xml
02-Dec-2024 21:15:39 Finished task 'Test wheel' with result: Success
02-Dec-2024 21:15:39 Starting task 'Inject is_641' of type 'com.atlassian.bamboo.plugins.bamboo-variable-inject-plugin:inject'
02-Dec-2024 21:15:39 Injected variable bamboo.inject.is_641=False in LOCAL scope
02-Dec-2024 21:15:39 Finished task 'Inject is_641' with result: Success
02-Dec-2024 21:15:39 Starting task 'Tar work directory' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
02-Dec-2024 21:15:39
Beginning to execute external process for build 'CASASPECS - Dev Test 3.10 - CAS-13671 - Test Tasks Many Linux 2.28 Py3.10 #6 (CASASPECS-DEVTESTPY31035-TTM228PY38-6)'
... running command line:
/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-DEVTESTPY31035-TTM228PY38-6-ScriptBuildTask-5077635656573603215.sh
... in: /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31035-TTM228PY38
02-Dec-2024 21:15:39 Package working directory
02-Dec-2024 21:15:39 nosedir/
02-Dec-2024 21:15:39 nosedir/test_task_sdcal/
02-Dec-2024 21:15:39 nosedir/test_task_sdcal/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdcal/test_task_sdcal.py
02-Dec-2024 21:15:39 nosedir/test_task_sdcal/casa-20241203-021340.log
02-Dec-2024 21:15:39 nosedir/test_task_sdcal/short_summary.log
02-Dec-2024 21:15:39 nosedir/test_task_sdgaincal/
02-Dec-2024 21:15:39 nosedir/test_task_sdgaincal/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdgaincal/casa-20241203-020657.log
02-Dec-2024 21:15:39 nosedir/test_task_sdgaincal/test_task_sdgaincal.py
02-Dec-2024 21:15:39 nosedir/test_task_sdgaincal/short_summary.log
02-Dec-2024 21:15:39 nosedir/test_task_sdfit/
02-Dec-2024 21:15:39 nosedir/test_task_sdfit/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdfit/test_task_sdfit.py
02-Dec-2024 21:15:39 nosedir/test_task_sdfit/casa-20241203-021148.log
02-Dec-2024 21:15:39 nosedir/test_task_sdfit/short_summary.log
02-Dec-2024 21:15:39 nosedir/test_task_sdbaseline/
02-Dec-2024 21:15:39 nosedir/test_task_sdbaseline/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdbaseline/casa-20241203-014043.log
02-Dec-2024 21:15:39 nosedir/test_task_sdbaseline/short_summary.log
02-Dec-2024 21:15:39 nosedir/test_task_sdbaseline/test_task_sdbaseline.py
02-Dec-2024 21:15:39 nosedir/xml/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdcal/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdcal/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdgaincal/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdgaincal/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdfit/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdfit/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdbaseline/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdbaseline/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdsmooth/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdsmooth/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdatmcor/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdatmcor/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdfixscan/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdfixscan/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdsidebandsplit/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdsidebandsplit/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_imbaseline/
02-Dec-2024 21:15:39 nosedir/xml/test_task_imbaseline/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdpolaverage/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdpolaverage/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_nrobeamaverage/
02-Dec-2024 21:15:39 nosedir/xml/test_task_nrobeamaverage/nose.xml
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdtimeaverage/
02-Dec-2024 21:15:39 nosedir/xml/test_task_sdtimeaverage/nose.xml
02-Dec-2024 21:15:39 nosedir/test_task_sdsmooth/
02-Dec-2024 21:15:39 nosedir/test_task_sdsmooth/casa-20241203-021113.log
02-Dec-2024 21:15:39 nosedir/test_task_sdsmooth/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdsmooth/test_task_sdsmooth.py
02-Dec-2024 21:15:39 nosedir/test_task_sdsmooth/short_summary.log
02-Dec-2024 21:15:39 nosedir/test_task_sdatmcor/
02-Dec-2024 21:15:39 nosedir/test_task_sdatmcor/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdatmcor/test_task_sdatmcor.py
02-Dec-2024 21:15:39 nosedir/test_task_sdatmcor/casa-20241203-020223.log
02-Dec-2024 21:15:39 nosedir/test_task_sdatmcor/short_summary.log
02-Dec-2024 21:15:39 nosedir/requirements.txt
02-Dec-2024 21:15:39 nosedir/test_task_sdfixscan/
02-Dec-2024 21:15:39 nosedir/test_task_sdfixscan/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdfixscan/test_task_sdfixscan.py
02-Dec-2024 21:15:39 nosedir/test_task_sdfixscan/casa-20241203-020649.log
02-Dec-2024 21:15:39 nosedir/test_task_sdfixscan/short_summary.log
02-Dec-2024 21:15:39 nosedir/test_task_sdsidebandsplit/
02-Dec-2024 21:15:39 nosedir/test_task_sdsidebandsplit/casa-20241203-021334.log
02-Dec-2024 21:15:39 nosedir/test_task_sdsidebandsplit/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdsidebandsplit/test_task_sdsidebandsplit.py
02-Dec-2024 21:15:39 nosedir/test_task_sdsidebandsplit/short_summary.log
02-Dec-2024 21:15:39 nosedir/test_task_imbaseline/
02-Dec-2024 21:15:39 nosedir/test_task_imbaseline/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_imbaseline/casa-20241203-014346.log
02-Dec-2024 21:15:39 nosedir/test_task_imbaseline/test_task_imbaseline.py
02-Dec-2024 21:15:39 nosedir/test_task_sdpolaverage/
02-Dec-2024 21:15:39 nosedir/test_task_sdpolaverage/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdpolaverage/test_task_sdpolaverage.py
02-Dec-2024 21:15:39 nosedir/test_task_sdpolaverage/casa-20241203-021230.log
02-Dec-2024 21:15:39 nosedir/test_task_sdpolaverage/short_summary.log
02-Dec-2024 21:15:39 nosedir/test_task_nrobeamaverage/
02-Dec-2024 21:15:39 nosedir/test_task_nrobeamaverage/casa-20241203-021456.log
02-Dec-2024 21:15:39 nosedir/test_task_nrobeamaverage/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_nrobeamaverage/test_task_nrobeamaverage.py
02-Dec-2024 21:15:39 nosedir/test_task_nrobeamaverage/short_summary.log
02-Dec-2024 21:15:39 nosedir/test_task_sdtimeaverage/
02-Dec-2024 21:15:39 nosedir/test_task_sdtimeaverage/conftest.py
02-Dec-2024 21:15:39 nosedir/test_task_sdtimeaverage/test_task_sdtimeaverage.py
02-Dec-2024 21:15:39 nosedir/test_task_sdtimeaverage/casa-20241203-021236.log
02-Dec-2024 21:15:39 nosedir/test_task_sdtimeaverage/short_summary.log
02-Dec-2024 21:15:39 nosedir/summary_of_failed.log
02-Dec-2024 21:15:40 total 181104
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci 15001   1854204 Dec  3 01:37 casatasks-6.7.0.0a13671.dev7-py3-none-any.whl
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root     951643 Dec  3 02:15 casatasks_test.log
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci 15001    129239 Dec  3 01:37 casatestutils-6.7.0.0a13671.dev7-py3-none-any.whl
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci 15001 182033659 Dec  3 01:37 casatools-6.7.0.0a13671.dev7-cp310-cp310-manylinux_2_28_x86_64.whl
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root        823 Dec  3 02:15 requirements.txt
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root      29182 Dec  3 02:15 test_task_imbaseline.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root        763 Dec  3 02:15 test_task_nrobeamaverage.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root       5020 Dec  3 02:15 test_task_sdatmcor.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root      17967 Dec  3 02:15 test_task_sdbaseline.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root       6626 Dec  3 02:15 test_task_sdcal.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root       4156 Dec  3 02:15 test_task_sdfit.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root       1841 Dec  3 02:15 test_task_sdfixscan.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root       1569 Dec  3 02:15 test_task_sdgaincal.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root        565 Dec  3 02:15 test_task_sdpolaverage.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root       2433 Dec  3 02:15 test_task_sdsidebandsplit.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root       3927 Dec  3 02:15 test_task_sdsmooth.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root       4379 Dec  3 02:15 test_task_sdtimeaverage.xml
02-Dec-2024 21:15:40 -rw-r--r-- 1 casaci root     358915 Dec  3 02:15 work-manylinux_228_casatasks-3.10.tar.gz
02-Dec-2024 21:15:40 Done packaging working directory
02-Dec-2024 21:15:40 Finished task 'Tar work directory' with result: Success
02-Dec-2024 21:15:40 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
02-Dec-2024 21:15:40 Parsing test results under /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31035-TTM228PY38...
02-Dec-2024 21:15:40 Finished task 'Parse tests' with result: Success
02-Dec-2024 21:15:40
Substituting variable: ${bamboo.repository.branch.name} with CAS-13671
02-Dec-2024 21:15:40
Substituting variable: ${bamboo.planRepository.branchName} with CAS-13671
02-Dec-2024 21:15:40 Starting task 'Upload wheel' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
02-Dec-2024 21:15:40
Beginning to execute external process for build 'CASASPECS - Dev Test 3.10 - CAS-13671 - Test Tasks Many Linux 2.28 Py3.10 #6 (CASASPECS-DEVTESTPY31035-TTM228PY38-6)'
... running command line:
/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-DEVTESTPY31035-TTM228PY38-6-ScriptBuildTask-6533676806962232211.sh
... in: /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31035-TTM228PY38
02-Dec-2024 21:15:40 Ticket fix version: CASA 6.ACTIVE
02-Dec-2024 21:15:40 No matching version found
02-Dec-2024 21:15:42 Collecting twine
02-Dec-2024 21:15:42   Downloading twine-6.0.1-py3-none-any.whl (39 kB)
02-Dec-2024 21:15:42 Collecting requests-toolbelt!=0.9.0,>=0.8.0
02-Dec-2024 21:15:42   Downloading requests_toolbelt-1.0.0-py2.py3-none-any.whl (54 kB)
02-Dec-2024 21:15:42      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 54.5/54.5 KB 12.5 MB/s eta 0:00:00
02-Dec-2024 21:15:42 Collecting requests>=2.20
02-Dec-2024 21:15:42   Downloading requests-2.32.3-py3-none-any.whl (64 kB)
02-Dec-2024 21:15:42      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 64.9/64.9 KB 14.1 MB/s eta 0:00:00
02-Dec-2024 21:15:42 Collecting urllib3>=1.26.0
02-Dec-2024 21:15:42   Downloading urllib3-2.2.3-py3-none-any.whl (126 kB)
02-Dec-2024 21:15:42      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 126.3/126.3 KB 25.0 MB/s eta 0:00:00
02-Dec-2024 21:15:42 Collecting readme-renderer>=35.0
02-Dec-2024 21:15:42   Downloading readme_renderer-44.0-py3-none-any.whl (13 kB)
02-Dec-2024 21:15:42 Collecting rfc3986>=1.4.0
02-Dec-2024 21:15:42   Downloading rfc3986-2.0.0-py2.py3-none-any.whl (31 kB)
02-Dec-2024 21:15:42 Collecting rich>=12.0.0
02-Dec-2024 21:15:42   Downloading rich-13.9.4-py3-none-any.whl (242 kB)
02-Dec-2024 21:15:42      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 242.4/242.4 KB 40.3 MB/s eta 0:00:00
02-Dec-2024 21:15:42 Collecting packaging
02-Dec-2024 21:15:42   Downloading packaging-24.2-py3-none-any.whl (65 kB)
02-Dec-2024 21:15:42      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 65.5/65.5 KB 13.7 MB/s eta 0:00:00
02-Dec-2024 21:15:42 Collecting pkginfo>=1.8.1
02-Dec-2024 21:15:42   Downloading pkginfo-1.12.0-py3-none-any.whl (32 kB)
02-Dec-2024 21:15:42 Collecting keyring>=15.1
02-Dec-2024 21:15:42   Downloading keyring-25.5.0-py3-none-any.whl (39 kB)
02-Dec-2024 21:15:42 Collecting jaraco.classes
02-Dec-2024 21:15:42   Downloading jaraco.classes-3.4.0-py3-none-any.whl (6.8 kB)
02-Dec-2024 21:15:42 Collecting jaraco.context
02-Dec-2024 21:15:42   Downloading jaraco.context-6.0.1-py3-none-any.whl (6.8 kB)
02-Dec-2024 21:15:42 Collecting jaraco.functools
02-Dec-2024 21:15:42   Downloading jaraco.functools-4.1.0-py3-none-any.whl (10 kB)
02-Dec-2024 21:15:42 Collecting jeepney>=0.4.2
02-Dec-2024 21:15:42   Downloading jeepney-0.8.0-py3-none-any.whl (48 kB)
02-Dec-2024 21:15:42      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 48.4/48.4 KB 10.1 MB/s eta 0:00:00
02-Dec-2024 21:15:43 Collecting importlib-metadata>=4.11.4
02-Dec-2024 21:15:43   Downloading importlib_metadata-8.5.0-py3-none-any.whl (26 kB)
02-Dec-2024 21:15:43 Collecting SecretStorage>=3.2
02-Dec-2024 21:15:43   Downloading SecretStorage-3.3.3-py3-none-any.whl (15 kB)
02-Dec-2024 21:15:43 Collecting Pygments>=2.5.1
02-Dec-2024 21:15:43   Downloading pygments-2.18.0-py3-none-any.whl (1.2 MB)
02-Dec-2024 21:15:43      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 63.7 MB/s eta 0:00:00
02-Dec-2024 21:15:43 Collecting docutils>=0.21.2
02-Dec-2024 21:15:43   Downloading docutils-0.21.2-py3-none-any.whl (587 kB)
02-Dec-2024 21:15:43      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 587.4/587.4 KB 63.1 MB/s eta 0:00:00
02-Dec-2024 21:15:43 Collecting nh3>=0.2.14
02-Dec-2024 21:15:43   Downloading nh3-0.2.19-cp38-abi3-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (748 kB)
02-Dec-2024 21:15:43      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 748.3/748.3 KB 60.7 MB/s eta 0:00:00
02-Dec-2024 21:15:43 Collecting certifi>=2017.4.17
02-Dec-2024 21:15:43   Downloading certifi-2024.8.30-py3-none-any.whl (167 kB)
02-Dec-2024 21:15:43      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 167.3/167.3 KB 32.1 MB/s eta 0:00:00
02-Dec-2024 21:15:43 Collecting idna<4,>=2.5
02-Dec-2024 21:15:43   Downloading idna-3.10-py3-none-any.whl (70 kB)
02-Dec-2024 21:15:43      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 70.4/70.4 KB 15.3 MB/s eta 0:00:00
02-Dec-2024 21:15:43 Collecting charset-normalizer<4,>=2
02-Dec-2024 21:15:43   Downloading charset_normalizer-3.4.0-cp310-cp310-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (144 kB)
02-Dec-2024 21:15:43      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 144.8/144.8 KB 25.8 MB/s eta 0:00:00
02-Dec-2024 21:15:43 Collecting typing-extensions<5.0,>=4.0.0
02-Dec-2024 21:15:43   Downloading typing_extensions-4.12.2-py3-none-any.whl (37 kB)
02-Dec-2024 21:15:43 Collecting markdown-it-py>=2.2.0
02-Dec-2024 21:15:43   Downloading markdown_it_py-3.0.0-py3-none-any.whl (87 kB)
02-Dec-2024 21:15:43      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 87.5/87.5 KB 18.4 MB/s eta 0:00:00
02-Dec-2024 21:15:44 Collecting zipp>=3.20
02-Dec-2024 21:15:44   Downloading zipp-3.21.0-py3-none-any.whl (9.6 kB)
02-Dec-2024 21:15:44 Collecting mdurl~=0.1
02-Dec-2024 21:15:44   Downloading mdurl-0.1.2-py3-none-any.whl (10.0 kB)
02-Dec-2024 21:15:44 Collecting cryptography>=2.0
02-Dec-2024 21:15:44   Downloading cryptography-44.0.0-cp39-abi3-manylinux_2_28_x86_64.whl (4.2 MB)
02-Dec-2024 21:15:44      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.2/4.2 MB 54.3 MB/s eta 0:00:00
02-Dec-2024 21:15:44 Collecting more-itertools
02-Dec-2024 21:15:44   Downloading more_itertools-10.5.0-py3-none-any.whl (60 kB)
02-Dec-2024 21:15:44      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 61.0/61.0 KB 11.9 MB/s eta 0:00:00
02-Dec-2024 21:15:44 Collecting backports.tarfile
02-Dec-2024 21:15:44   Downloading backports.tarfile-1.2.0-py3-none-any.whl (30 kB)
02-Dec-2024 21:15:45 Collecting cffi>=1.12
02-Dec-2024 21:15:45   Downloading cffi-1.17.1-cp310-cp310-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
02-Dec-2024 21:15:45      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 446.2/446.2 KB 53.4 MB/s eta 0:00:00
02-Dec-2024 21:15:45 Collecting pycparser
02-Dec-2024 21:15:45   Downloading pycparser-2.22-py3-none-any.whl (117 kB)
02-Dec-2024 21:15:45      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 117.6/117.6 KB 24.1 MB/s eta 0:00:00
02-Dec-2024 21:15:45 Installing collected packages: nh3, zipp, urllib3, typing-extensions, rfc3986, Pygments, pycparser, pkginfo, packaging, more-itertools, mdurl, jeepney, idna, docutils, charset-normalizer, certifi, backports.tarfile, requests, readme-renderer, markdown-it-py, jaraco.functools, jaraco.context, jaraco.classes, importlib-metadata, cffi, rich, requests-toolbelt, cryptography, SecretStorage, keyring, twine
02-Dec-2024 21:15:48 Successfully installed Pygments-2.18.0 SecretStorage-3.3.3 backports.tarfile-1.2.0 certifi-2024.8.30 cffi-1.17.1 charset-normalizer-3.4.0 cryptography-44.0.0 docutils-0.21.2 idna-3.10 importlib-metadata-8.5.0 jaraco.classes-3.4.0 jaraco.context-6.0.1 jaraco.functools-4.1.0 jeepney-0.8.0 keyring-25.5.0 markdown-it-py-3.0.0 mdurl-0.1.2 more-itertools-10.5.0 nh3-0.2.19 packaging-24.2 pkginfo-1.12.0 pycparser-2.22 readme-renderer-44.0 requests-2.32.3 requests-toolbelt-1.0.0 rfc3986-2.0.0 rich-13.9.4 twine-6.0.1 typing-extensions-4.12.2 urllib3-2.2.3 zipp-3.21.0
02-Dec-2024 21:15:48 WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
02-Dec-2024 21:15:48 WARNING: You are using pip version 22.0.4; however, version 24.3.1 is available.
02-Dec-2024 21:15:48 You should consider upgrading via the '/usr/local/bin/python3.10 -m pip install --upgrade pip' command.
02-Dec-2024 21:15:49 Cloning into 'casa-build-utils'...
02-Dec-2024 21:15:50 Uploading distributions to
02-Dec-2024 21:15:50 https://casa-pip.nrao.edu:443/repository/casa-dev-wheel/
02-Dec-2024 21:15:50 Uploading casatasks-6.7.0.0a13671.dev7-py3-none-any.whl
02-Dec-2024 21:15:50
02-Dec-2024 21:15:50   0% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 0.0/1.9 MB--:--?
02-Dec-2024 21:15:50 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.9/1.9 MB00:00106.6 MB/s
02-Dec-2024 21:15:50 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.9/1.9 MB00:00106.6 MB/s
02-Dec-2024 21:15:50
02-Dec-2024 21:15:50 Finished task 'Upload wheel' with result: Success
02-Dec-2024 21:15:50 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
02-Dec-2024 21:15:50
Beginning to execute external process for build 'CASASPECS - Dev Test 3.10 - CAS-13671 - Test Tasks Many Linux 2.28 Py3.10 #6 (CASASPECS-DEVTESTPY31035-TTM228PY38-6)'
... running command line:
/export/home/cbt-el7-7/bamboohome/temp/CASASPECS-DEVTESTPY31035-TTM228PY38-6-ScriptBuildTask-5968821559249732044.sh
... in: /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31035-TTM228PY38
02-Dec-2024 21:15:50 Starting cleanup
02-Dec-2024 21:16:01 wheel-container-test
02-Dec-2024 21:16:02 wheel-container-test
02-Dec-2024 21:16:02 Cleanup complete
02-Dec-2024 21:16:02 Finished task 'Cleanup' with result: Success
02-Dec-2024 21:16:02 Running post build plugin 'Docker Container Cleanup'
02-Dec-2024 21:16:02
Beginning to execute external process for build 'CASASPECS - Dev Test 3.10 - CAS-13671 - Test Tasks Many Linux 2.28 Py3.10 #6 (CASASPECS-DEVTESTPY31035-TTM228PY38-6)'
... running command line:
/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-7/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTPY31035-TTM228PY38
02-Dec-2024 21:16:02 Error response from daemon: No such container: wheel-container-test
02-Dec-2024 21:16:02 Running post build plugin 'NCover Results Collector'
02-Dec-2024 21:16:02 Running post build plugin 'Build Results Label Collector'
02-Dec-2024 21:16:02 Running post build plugin 'Clover Results Collector'
02-Dec-2024 21:16:02 Running post build plugin 'npm Cache Cleanup'
02-Dec-2024 21:16:02 Running post build plugin 'Artifact Copier'
02-Dec-2024 21:16:02 Publishing an artifact: Workdir
02-Dec-2024 21:16:02 Finished publishing of artifact Non required job artifact Http Compression On : [Workdir], patterns: [work*.tar.gz] anchored at: [wheeldirectory/] in 99.39 ms
02-Dec-2024 21:16:02 Publishing an artifact: XML Directories
02-Dec-2024 21:16:03 Finished publishing of artifact Non required job artifact Http Compression On : [XML Directories], patterns: [**/*.xml] anchored at: [wheeldirectory/] in 113.7 ms
02-Dec-2024 21:16:03 Publishing an artifact: requirements.txt
02-Dec-2024 21:16:03 Finished publishing of artifact Non required job artifact Http Compression On : [requirements.txt], patterns: [requirements.txt] anchored at: [wheeldirectory/] in 27.16 ms
02-Dec-2024 21:16:03 Finalising the build...
02-Dec-2024 21:16:03 Stopping timer.
02-Dec-2024 21:16:03 Build CASASPECS-DEVTESTPY31035-TTM228PY38-6 completed.
02-Dec-2024 21:16:03 Running on server: post build plugin 'Build Hanging Detection Configuration'
02-Dec-2024 21:16:03 Running on server: post build plugin 'NCover Results Collector'
02-Dec-2024 21:16:03 Running on server: post build plugin 'Build Labeller'
02-Dec-2024 21:16:03 Running on server: post build plugin 'Clover Delta Calculator'
02-Dec-2024 21:16:03 Running on server: post build plugin 'Maven Dependencies Postprocessor'
02-Dec-2024 21:16:03 All post build plugins have finished
02-Dec-2024 21:16:03 Generating build results summary...
02-Dec-2024 21:16:03 Saving build results to disk...
02-Dec-2024 21:16:03 Store variable context...
02-Dec-2024 21:16:03 Finished building CASASPECS-DEVTESTPY31035-TTM228PY38-6.