Build: #1 was successful

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

Build log

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

13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 82: diff between 1/SIGMA**2 X 1/(FPARAM[82])**2 and WEIGHT_SPECTRUM[82] 2.4373373889829963e-05 0.000168752203535405
13-Apr-2026 12:20:25 7.696069127148141e-07 % 5.149741575164227e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 83: diff between 1/SIGMA**2 X 1/(FPARAM[83])**2 and WEIGHT_SPECTRUM[83] -4.8289960432157386e-05 0.00010321954732717131
13-Apr-2026 12:20:25 -1.5135926183590756e-06 % 3.1344808081932345e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 84: diff between 1/SIGMA**2 X 1/(FPARAM[84])**2 and WEIGHT_SPECTRUM[84] -5.973869110675878e-05 0.0002807912687785574
13-Apr-2026 12:20:25 -1.886010620015602e-06 % 8.589316216132033e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 85: diff between 1/SIGMA**2 X 1/(FPARAM[85])**2 and WEIGHT_SPECTRUM[85] 0.0002646423799887998 0.0002306879910065618
13-Apr-2026 12:20:25 8.32331496378257e-06 % 7.0674416746938314e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 86: diff between 1/SIGMA**2 X 1/(FPARAM[86])**2 and WEIGHT_SPECTRUM[86] -2.50708794737875e-05 -5.661770956066903e-05
13-Apr-2026 12:20:25 -7.911086288705563e-07 % -1.7119233252901912e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 87: diff between 1/SIGMA**2 X 1/(FPARAM[87])**2 and WEIGHT_SPECTRUM[87] 6.522142484755022e-05 0.00010496174263607827
13-Apr-2026 12:20:25 2.039888472422369e-06 % 3.1823624939885302e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 88: diff between 1/SIGMA**2 X 1/(FPARAM[88])**2 and WEIGHT_SPECTRUM[88] 0.00036728224222315475 0.00034223054672111175
13-Apr-2026 12:20:25 1.1284942052788368e-05 % 1.0292063863633751e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 89: diff between 1/SIGMA**2 X 1/(FPARAM[89])**2 and WEIGHT_SPECTRUM[89] -0.00014460561715168296 0.0003207763124919438
13-Apr-2026 12:20:25 -4.399279838763849e-06 % 9.505622027399997e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 90: diff between 1/SIGMA**2 X 1/(FPARAM[90])**2 and WEIGHT_SPECTRUM[90] -0.00011461086387498653 0.00039623051497983397
13-Apr-2026 12:20:25 -3.467151181396217e-06 % 1.174337873439306e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 91: diff between 1/SIGMA**2 X 1/(FPARAM[91])**2 and WEIGHT_SPECTRUM[91] 6.456625715145492e-05 0.0003180636035722273
13-Apr-2026 12:20:25 1.9433487181077447e-06 % 9.28938316563151e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 92: diff between 1/SIGMA**2 X 1/(FPARAM[92])**2 and WEIGHT_SPECTRUM[92] 8.807283666101284e-05 -4.10362372349482e-06
13-Apr-2026 12:20:25 2.6252950818937264e-06 % -1.2017485790158392e-07 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 93: diff between 1/SIGMA**2 X 1/(FPARAM[93])**2 and WEIGHT_SPECTRUM[93] 0.00048270889192281174 0.00027695990411302773
13-Apr-2026 12:20:25 1.434990348109085e-05 % 8.037578495348583e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 94: diff between 1/SIGMA**2 X 1/(FPARAM[94])**2 and WEIGHT_SPECTRUM[94] 8.92282741915551e-05 0.0003953709001507377
13-Apr-2026 12:20:25 2.6423528129559337e-06 % 1.142510365534796e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 95: diff between 1/SIGMA**2 X 1/(FPARAM[95])**2 and WEIGHT_SPECTRUM[95] -0.00011922873000003165 -1.5688332496210933e-05
13-Apr-2026 12:20:25 -3.5361092765581317e-06 % -4.558187284943939e-07 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 96: diff between 1/SIGMA**2 X 1/(FPARAM[96])**2 and WEIGHT_SPECTRUM[96] -0.00013367999281399534 0.00015512142545048846
13-Apr-2026 12:20:25 -3.974170120225462e-06 % 4.5310309207541285e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 97: diff between 1/SIGMA**2 X 1/(FPARAM[97])**2 and WEIGHT_SPECTRUM[97] 0.00037664412548110704 4.586773547998746e-05
13-Apr-2026 12:20:25 1.1328202660762169e-05 % 1.3413208997665228e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 98: diff between 1/SIGMA**2 X 1/(FPARAM[98])**2 and WEIGHT_SPECTRUM[98] 1.8070886198984226e-05 0.0004283089538148488
13-Apr-2026 12:20:25 5.409674680772336e-07 % 1.264310786286549e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 99: diff between 1/SIGMA**2 X 1/(FPARAM[99])**2 and WEIGHT_SPECTRUM[99] 0.00018832554223990883 0.000340715180755069
13-Apr-2026 12:20:25 5.599287921061252e-06 % 9.815314437520155e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 100: diff between 1/SIGMA**2 X 1/(FPARAM[100])**2 and WEIGHT_SPECTRUM[100] 8.177762902050745e-05 -7.714391495028394e-05
13-Apr-2026 12:20:25 2.4048246264497372e-06 % -2.206721408459397e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 101: diff between 1/SIGMA**2 X 1/(FPARAM[101])**2 and WEIGHT_SPECTRUM[101] 0.0005206697010180505 9.818402577366214e-05
13-Apr-2026 12:20:25 1.528992313302044e-05 % 2.8094159559602106e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 102: diff between 1/SIGMA**2 X 1/(FPARAM[102])**2 and WEIGHT_SPECTRUM[102] -0.00012399494562487234 0.0004818899515157682
13-Apr-2026 12:20:25 -3.6467467657087195e-06 % 1.3758735942868515e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 103: diff between 1/SIGMA**2 X 1/(FPARAM[103])**2 and WEIGHT_SPECTRUM[103] -8.117890001813066e-05 0.00013283681710163364
13-Apr-2026 12:20:25 -2.3766574119395775e-06 % 3.7894850235019376e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 104: diff between 1/SIGMA**2 X 1/(FPARAM[104])**2 and WEIGHT_SPECTRUM[104] 0.00011876667394972173 0.00040017531955527375
13-Apr-2026 12:20:25 3.5726640304689906e-06 % 1.1343601648187533e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 105: diff between 1/SIGMA**2 X 1/(FPARAM[105])**2 and WEIGHT_SPECTRUM[105] 0.00028899124345116434 0.0003026538688573055
13-Apr-2026 12:20:25 8.752050164392563e-06 % 8.566238032422046e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 106: diff between 1/SIGMA**2 X 1/(FPARAM[106])**2 and WEIGHT_SPECTRUM[106] 0.00029973344771860866 -0.00013164713800506433
13-Apr-2026 12:20:25 9.311331640124489e-06 % -3.69876628560748e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 107: diff between 1/SIGMA**2 X 1/(FPARAM[107])**2 and WEIGHT_SPECTRUM[107] 0.00038247749807851505 0.00041078413823925075
13-Apr-2026 12:20:25 1.181859697904343e-05 % 1.151166771614822e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 108: diff between 1/SIGMA**2 X 1/(FPARAM[108])**2 and WEIGHT_SPECTRUM[108] 8.064889243541984e-06 1.5489893030462554e-05
13-Apr-2026 12:20:25 2.532725871443662e-07 % 4.3012009198063133e-07 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 109: diff between 1/SIGMA**2 X 1/(FPARAM[109])**2 and WEIGHT_SPECTRUM[109] 0.0003108497317043657 0.0003392630774214922
13-Apr-2026 12:20:25 9.713959252878098e-06 % 9.475152249672333e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 110: diff between 1/SIGMA**2 X 1/(FPARAM[110])**2 and WEIGHT_SPECTRUM[110] 0.0002382699462941673 0.0004435260871105129
13-Apr-2026 12:20:25 7.430830042694347e-06 % 1.2378108364385603e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 111: diff between 1/SIGMA**2 X 1/(FPARAM[111])**2 and WEIGHT_SPECTRUM[111] 0.0002518527930988057 0.00018790852482197806
13-Apr-2026 12:20:25 7.751446728170256e-06 % 5.205302556920961e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 112: diff between 1/SIGMA**2 X 1/(FPARAM[112])**2 and WEIGHT_SPECTRUM[112] 0.0002477667726452637 0.0002022313597080938
13-Apr-2026 12:20:25 7.525765961685548e-06 % 5.6568409524778605e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 113: diff between 1/SIGMA**2 X 1/(FPARAM[113])**2 and WEIGHT_SPECTRUM[113] 0.0001127897548940382 0.0001286817332584178
13-Apr-2026 12:20:25 3.449688231486205e-06 % 3.6099393076581322e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 114: diff between 1/SIGMA**2 X 1/(FPARAM[114])**2 and WEIGHT_SPECTRUM[114] -0.0001390001857544121 0.00022070866270951228
13-Apr-2026 12:20:25 -4.277861682502366e-06 % 6.272568328572215e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 115: diff between 1/SIGMA**2 X 1/(FPARAM[115])**2 and WEIGHT_SPECTRUM[115] 0.00017463496078562457 -3.6452964195632376e-05
13-Apr-2026 12:20:25 5.4288587434391414e-06 % -1.0534715165571568e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 116: diff between 1/SIGMA**2 X 1/(FPARAM[116])**2 and WEIGHT_SPECTRUM[116] 0.00031452353550776024 0.000617406453784497
13-Apr-2026 12:20:25 9.884054094794064e-06 % 1.785039218608886e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 117: diff between 1/SIGMA**2 X 1/(FPARAM[117])**2 and WEIGHT_SPECTRUM[117] 0.0001022930778162845 0.0003385835525477887
13-Apr-2026 12:20:25 3.255575578543313e-06 % 1.001759554455914e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 118: diff between 1/SIGMA**2 X 1/(FPARAM[118])**2 and WEIGHT_SPECTRUM[118] 0.00024448239946650574 7.557083790743491e-06
13-Apr-2026 12:20:25 7.929336164806108e-06 % 2.2665179472466035e-07 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 119: diff between 1/SIGMA**2 X 1/(FPARAM[119])**2 and WEIGHT_SPECTRUM[119] -2.6198091291007586e-05 0.00033151857041957555
13-Apr-2026 12:20:25 -8.48165515171887e-07 % 1.0103846347422004e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 120: diff between 1/SIGMA**2 X 1/(FPARAM[120])**2 and WEIGHT_SPECTRUM[120] 0.00030485935849355883 0.00029190921031840844
13-Apr-2026 12:20:25 1.0027259351468884e-05 % 9.01206222611165e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 121: diff between 1/SIGMA**2 X 1/(FPARAM[121])**2 and WEIGHT_SPECTRUM[121] -4.323769098846242e-05 -8.509679992130259e-05
13-Apr-2026 12:20:25 -1.4292735222443903e-06 % -2.6840707888260907e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 122: diff between 1/SIGMA**2 X 1/(FPARAM[122])**2 and WEIGHT_SPECTRUM[122] 3.061347797483904e-05 0.00022753920256946003
13-Apr-2026 12:20:25 1.0718066687033362e-06 % 7.548029562378216e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 123: diff between 1/SIGMA**2 X 1/(FPARAM[123])**2 and WEIGHT_SPECTRUM[123] 0.0001198574022964749 0.00030981887630332494
13-Apr-2026 12:20:25 4.629104857596006e-06 % 1.0839918702098592e-05 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 124: diff between 1/SIGMA**2 X 1/(FPARAM[124])**2 and WEIGHT_SPECTRUM[124] -1.28034134831978e-05 0.00016469755200887448
13-Apr-2026 12:20:25 -5.71903779426735e-07 % 6.367919683061561e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 125: diff between 1/SIGMA**2 X 1/(FPARAM[125])**2 and WEIGHT_SPECTRUM[125] 8.146243521878205e-05 0.00020233114491929882
13-Apr-2026 12:20:25 4.334441717664649e-06 % 8.723969173137133e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 126: diff between 1/SIGMA**2 X 1/(FPARAM[126])**2 and WEIGHT_SPECTRUM[126] 8.631705122752464e-05 -0.00016473925961690838
13-Apr-2026 12:20:25 5.48234449028032e-06 % -7.850287451233031e-06 %
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 pol0 & pol1 ch 127: diff between 1/SIGMA**2 X 1/(FPARAM[127])**2 and WEIGHT_SPECTRUM[127] 4.008430755675363e-05 9.41018111006997e-05
13-Apr-2026 12:20:25 2.789929184392109e-06 % 4.837875533460108e-06 %
13-Apr-2026 12:20:25 PASSED
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 test_task_sdcal.py::sdcal_test_ps::test_ps00
13-Apr-2026 12:20:25 test_ps00 --- default parameters (raises an error).
13-Apr-2026 12:20:25 PASSED
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 test_task_sdcal.py::sdcal_test_ps::test_ps01
13-Apr-2026 12:20:25 test_ps01 --- invalid calibration type.
13-Apr-2026 12:20:25 PASSED
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 test_task_sdcal.py::sdcal_test_ps::test_ps02 2026-04-13 16:20:26        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::Calibrater::selectvis (file /source/casa6/casatools/src/code/synthesis/MeasurementComponents/Calibrater.cc, line 4221)        Caught exception: Spw Expression: No match found for 99,
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::        Exception Reported: Error in data selection specification: Spw Expression: No match found for 99,
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 46, in sdcal
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+            cb.selectvis(spw=spw, scan=scan, field=field)
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/calibrater.py", line 82, in selectvis
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+            return self._swigobj.selectvis(time, spw, scan, field, intent, observation, baseline, uvrange, chanmode, nchan, start, step, mstart, mstep, msselect)
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/__casac__/calibrater.py", line 264, in selectvis
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+            return _calibrater.calibrater_selectvis(self, *args, **kwargs)
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+        RuntimeError: Error in data selection specification: Spw Expression: No match found for 99,
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa        Error in data selection specification: Spw Expression: No match found for 99,
13-Apr-2026 12:20:25 2026-04-13 16:20:26        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,
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 test_ps02 --- invalid selection (invalid spw selection).
13-Apr-2026 12:20:25 PASSED
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 test_task_sdcal.py::sdcal_test_ps::test_ps03 2026-04-13 16:20:26        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 58, in sdcal
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+            raise RuntimeError("overwrite is False and output file exists: {}".format(outfile))
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+        RuntimeError: overwrite is False and output file exists: uid___A002_X6218fb_X264.ms.sel.sky
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa        overwrite is False and output file exists: uid___A002_X6218fb_X264.ms.sel.sky
13-Apr-2026 12:20:25 2026-04-13 16:20:26        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
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 test_ps03 --- outfile exists (overwrite=False).
13-Apr-2026 12:20:25 PASSED
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 test_task_sdcal.py::sdcal_test_ps::test_ps04 2026-04-13 16:20:26        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 144, in sdcal
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+            raise RuntimeError('Output file name must be specified.')
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa+        RuntimeError: Output file name must be specified.
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa        Output file name must be specified.
13-Apr-2026 12:20:25 2026-04-13 16:20:26        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Output file name must be specified.
13-Apr-2026 12:20:25
13-Apr-2026 12:20:25 test_ps04 --- empty outfile.
13-Apr-2026 12:20:25 PASSED
13-Apr-2026 12:20:25
13-Apr-2026 12:20:26 test_task_sdcal.py::sdcal_test_ps::test_ps05 2026-04-13 16:20:26        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:26
13-Apr-2026 12:20:26 test_ps05 --- position switch calibration ('ps').
13-Apr-2026 12:20:26 PASSED
13-Apr-2026 12:20:26
13-Apr-2026 12:20:26 test_task_sdcal.py::sdcal_test_ps::test_ps05M 2026-04-13 16:20:26        WARN        ParallelDataHelper::getPartitionMap::casa        Number of subMSs (8) is greater than available scan,ddi pairs (4), setting nsubms to 4
13-Apr-2026 12:20:26 2026-04-13 16:20:27        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:27
13-Apr-2026 12:20:27 test_ps05M --- position switch calibration ('ps') for MMS.
13-Apr-2026 12:20:27 PASSED
13-Apr-2026 12:20:27
13-Apr-2026 12:20:27 test_task_sdcal.py::sdcal_test_ps::test_ps06 2026-04-13 16:20:27        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:27
13-Apr-2026 12:20:27 test_ps06 --- position switch calibration ('ps') with data selection.
13-Apr-2026 12:20:27 PASSED
13-Apr-2026 12:20:27
13-Apr-2026 12:20:27 test_task_sdcal.py::sdcal_test_ps::test_ps07 2026-04-13 16:20:27        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:27
13-Apr-2026 12:20:27 test_ps07 --- outfile exists (overwrite=True).
13-Apr-2026 12:20:27 PASSED
13-Apr-2026 12:20:27
13-Apr-2026 12:20:27 test_task_sdcal.py::sdcal_test_ps::test_ps08 2026-04-13 16:20:28        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::Calibrater::solve        Caught exception: Too many edge points (2 out of 1 points)
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::        Exception Reported: Error in Calibrater::solve.
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 157, in sdcal
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+            cb.solve()
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/calibrater.py", line 485, in solve
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+            return self._swigobj.solve()
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/__casac__/calibrater.py", line 1133, in solve
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+            return _calibrater.calibrater_solve(self)
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa+        RuntimeError: Error in Calibrater::solve.
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Error in Calibrater::solve.
13-Apr-2026 12:20:27 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in Calibrater::solve.
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_ps08 --- inappropriate calmode ('otfraster').
13-Apr-2026 12:20:28 PASSED
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_task_sdcal.py::sdcal_test_bug_fix_cas_12712::test_cas_12712_01 2026-04-13 16:20:28        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 PASSED
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster00 2026-04-13 16:20:28        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::sdcal::casa        could not convert string to float: 'auto'
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 187, in to_numeric_fraction
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+            fraction_numeric = float(fraction)
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+                               ^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+        ValueError: could not convert string to float: 'auto'
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+        
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+        During handling of the above exception, another exception occurred:
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+        
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+        Traceback (most recent call last):
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 149, in sdcal
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+            fraction_numeric = to_numeric_fraction(fraction)
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 192, in to_numeric_fraction
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+            raise RuntimeError('Invalid fraction value (original error message: "%s")' % (str(e)))
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+        RuntimeError: Invalid fraction value (original error message: "could not convert string to float: 'auto'")
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Invalid fraction value (original error message: "could not convert string to float: 'auto'")
13-Apr-2026 12:20:28 2026-04-13 16:20:28        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'")
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 PASSED
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster01 2026-04-13 16:20:28        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 151, in sdcal
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+            raise ValueError('Too many edge points. fraction must be < 0.5.')
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+        ValueError: Too many edge points. fraction must be < 0.5.
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Too many edge points. fraction must be < 0.5.
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Task sdcal raised an exception of class ValueError with the following message: Too many edge points. fraction must be < 0.5.
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_otfraster01 --- too many edge points (fraction 0.5).
13-Apr-2026 12:20:28 PASSED
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster02 2026-04-13 16:20:28        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 151, in sdcal
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+            raise ValueError('Too many edge points. fraction must be < 0.5.')
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa+        ValueError: Too many edge points. fraction must be < 0.5.
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Too many edge points. fraction must be < 0.5.
13-Apr-2026 12:20:28 2026-04-13 16:20:28        SEVERE        sdcal::::casa        Task sdcal raised an exception of class ValueError with the following message: Too many edge points. fraction must be < 0.5.
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_otfraster02 --- too many edge points (fraction 50%).
13-Apr-2026 12:20:28 PASSED
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster03 2026-04-13 16:20:29        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::Calibrater::solve        Caught exception: Too many edge points (20000 out of 9 points)
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::        Exception Reported: Error in Calibrater::solve.
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 157, in sdcal
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+            cb.solve()
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/calibrater.py", line 485, in solve
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+            return self._swigobj.solve()
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/__casac__/calibrater.py", line 1133, in solve
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+            return _calibrater.calibrater_solve(self)
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+        RuntimeError: Error in Calibrater::solve.
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa        Error in Calibrater::solve.
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in Calibrater::solve.
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_otfraster03 --- too many edge points (noff 100000).
13-Apr-2026 12:20:28 PASSED
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster06 2026-04-13 16:20:29        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::Calibrater::solve        Caught exception: No reference integration found in user-selected data. Please double-check your data selection criteria.
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::        Exception Reported: Error in Calibrater::solve.
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 157, in sdcal
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+            cb.solve()
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/calibrater.py", line 485, in solve
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+            return self._swigobj.solve()
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/__casac__/calibrater.py", line 1133, in solve
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+            return _calibrater.calibrater_solve(self)
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa+        RuntimeError: Error in Calibrater::solve.
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa        Error in Calibrater::solve.
13-Apr-2026 12:20:28 2026-04-13 16:20:29        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in Calibrater::solve.
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_otfraster06 --- inappropriate calibration mode ('ps').
13-Apr-2026 12:20:28 PASSED
13-Apr-2026 12:20:28
13-Apr-2026 12:20:28 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster07 2026-04-13 16:20:29        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:29
13-Apr-2026 12:20:29 test_otfraster07 --- OTF raster calibration ('otfraster') with default setting.
13-Apr-2026 12:20:29 PASSED
13-Apr-2026 12:20:29
13-Apr-2026 12:20:29 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster07M 2026-04-13 16:20:30        WARN        ParallelDataHelper::getPartitionMap::casa        Number of subMSs (8) is greater than available scan,ddi pairs (4), setting nsubms to 4
13-Apr-2026 12:20:30 2026-04-13 16:20:31        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:31
13-Apr-2026 12:20:31 test_otfraster07M --- OTF raster calibration ('otfraster') with default setting (MMS).
13-Apr-2026 12:20:31 PASSED
13-Apr-2026 12:20:31
13-Apr-2026 12:20:31 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster08 2026-04-13 16:20:32        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:32
13-Apr-2026 12:20:32 test_otfraster08 --- OTF raster calibration ('otfraster') with string fraction (numeric value).
13-Apr-2026 12:20:32 PASSED
13-Apr-2026 12:20:32
13-Apr-2026 12:20:32 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster09 2026-04-13 16:20:33        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:33
13-Apr-2026 12:20:33 test_otfraster09 --- OTF raster calibration ('otfraster') with string fraction (percentage).
13-Apr-2026 12:20:33 PASSED
13-Apr-2026 12:20:33
13-Apr-2026 12:20:33 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster10 2026-04-13 16:20:34        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:34
13-Apr-2026 12:20:34 test_otfraster10 --- OTF raster calibration ('otfraster') with numeric fraction.
13-Apr-2026 12:20:34 PASSED
13-Apr-2026 12:20:34
13-Apr-2026 12:20:34 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster11 2026-04-13 16:20:35        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:35
13-Apr-2026 12:20:35 test_otfraster11 --- OTF raster calibration ('otfraster') with auto detection.
13-Apr-2026 12:20:35 PASSED
13-Apr-2026 12:20:35
13-Apr-2026 12:20:36 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster12 2026-04-13 16:20:36        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:36
13-Apr-2026 12:20:36 test_otfraster12 --- OTF raster calibration ('otfraster') with custom noff.
13-Apr-2026 12:20:36 PASSED
13-Apr-2026 12:20:36
13-Apr-2026 12:20:37 test_task_sdcal.py::sdcal_test_otfraster::test_otfraster13 2026-04-13 16:20:37        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:38
13-Apr-2026 12:20:38 test_otfraster13 --- check if noff takes priority over fraction.
13-Apr-2026 12:20:38 PASSED
13-Apr-2026 12:20:38
13-Apr-2026 12:20:38 test_task_sdcal.py::sdcal_test_otf::test_otf01 2026-04-13 16:20:38        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:38
13-Apr-2026 12:20:38 test_otf01 --- Compute calibration table. calmode='otf' ms=squares.dec60_cs.ms.
13-Apr-2026 12:20:38 PASSED
13-Apr-2026 12:20:38
13-Apr-2026 12:20:38 test_task_sdcal.py::sdcal_test_otf::test_otf02 2026-04-13 16:20:38        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:38
13-Apr-2026 12:20:38 test_otf02 --- Compute calibration table. calmode='otf' ms=squares.dec60_cs.ms edges_fraction=20%.
13-Apr-2026 12:20:38 PASSED
13-Apr-2026 12:20:38
13-Apr-2026 12:20:38 test_task_sdcal.py::sdcal_test_otf::test_otf03 2026-04-13 16:20:38        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:38
13-Apr-2026 12:20:38 test_otf03 --- Compute calibration table. calmode='otf' ms=lissajous.ms.
13-Apr-2026 12:20:38 PASSED
13-Apr-2026 12:20:38
13-Apr-2026 12:20:38 test_task_sdcal.py::sdcal_test_otf::test_otf03M 2026-04-13 16:20:38        WARN        ParallelDataHelper::getPartitionMap::casa        Number of subMSs (8) is greater than available scan,ddi pairs (4), setting nsubms to 4
13-Apr-2026 12:20:38 2026-04-13 16:20:39        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_otf03 --- Compute calibration table. calmode='otf' ms=lissajous.ms (MMS).
13-Apr-2026 12:20:39 PASSED
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_task_sdcal.py::sdcal_test_otf::test_otf04 2026-04-13 16:20:39        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_otf04 --- Compute calibration table. calmode='otf' ms=lissajous.ms edges_fraction=20%.
13-Apr-2026 12:20:39 PASSED
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_task_sdcal.py::sdcal_test_otf::test_otf05 2026-04-13 16:20:39        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39 The following MS spws have no corresponding cal spws in squares.dec60_cs.edges_fraction_0.1.ms_caltable: 0 1 2 3
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::CTPatchedInterp::interpolate        In caltable squares.dec60_cs.edges_fraction_0.1.ms_caltable (SDSKY_OTF):
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_otf_075.tab: 0 1 2 3
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::CTPatchedInterp::interpolate        In caltable __sdcal_composite_mode_otf_075.tab (SDSKY_OTF):
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_otf05 --- Sky calibration. calmode='otf,apply' ms=squares.dec60_cs.ms.
13-Apr-2026 12:20:39 PASSED
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_task_sdcal.py::sdcal_test_otf::test_otf06 2026-04-13 16:20:39        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39 The following MS spws have no corresponding cal spws in lissajous.edges_new_fraction_0.1.ms_caltable: 0 1 2 3
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::CTPatchedInterp::interpolate        In caltable lissajous.edges_new_fraction_0.1.ms_caltable (SDSKY_OTF):
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
13-Apr-2026 12:20:39 2026-04-13 16:20:39        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_otf06 --- Sky calibration reusing caltable pre-computed with calmode='otf'. calmode='apply' ms=lissajous.ms.
13-Apr-2026 12:20:39 PASSED
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_task_sdcal.py::sdcal_test_otf::test_otf07 2026-04-13 16:20:40        WARN        sdcal::::casa        Intent selection for 'otf' or 'otfraster' should be 'OBSERVE_TARGET#ON_SOURCE'.
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::::casa+        However, the task is not allowed to set global intent selection since calmode contains 'tsys'.
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::::casa+        As a workaround, set intent selection locally when 'otf' or 'otfraster' calibration is performed.
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_otf_013.tab: 0 1 2 3
13-Apr-2026 12:20:39 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_tsys_061.tab: 0 1 2 3
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::CTPatchedInterp::interpolate        In caltable __sdcal_composite_mode_otf_013.tab (SDSKY_OTF):
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::CTPatchedInterp::interpolate        In caltable __sdcal_composite_mode_tsys_061.tab (B TSYS):
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
13-Apr-2026 12:20:39 2026-04-13 16:20:40        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=4; using NEAREST instead.
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_otf07 --- Sky calibration + Tsys conversion, composite calmode='otf,tsys,apply'. ms=lissajous.ms.
13-Apr-2026 12:20:39 PASSED
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_task_sdcal.py::sdcal_test_otf_ephem::test_otfephem01 2026-04-13 16:20:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:39
13-Apr-2026 12:20:39 test_otfephem01: Sky calibration of 'otf' mode for ephemeris object.
13-Apr-2026 12:20:39 PASSED
13-Apr-2026 12:20:39
13-Apr-2026 12:20:40 test_task_sdcal.py::sdcal_test_otf_ephem::test_otfephem02 2026-04-13 16:20:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::CTPatchedInterp::interpolate        In caltable __sdcal_composite_mode_otf_020.tab (SDSKY_OTF):
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::CTPatchedInterp::interpolate+         Insufficient solution channel sampling (nchan=1) for frequency-dependent LINEAR interpolation
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::CTPatchedInterp::interpolate+         of calibration for MS spw=0; using NEAREST instead.
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::::        MS obs=0,spw=0,ant=1 is selected for processing, but has no available calibration in __sdcal_composite_mode_otf_020.tab as mapped, and will be flagged.
13-Apr-2026 12:20:40
13-Apr-2026 12:20:40 test_otfephem02: On-the-fly application of 'otf' calibration mode for ephemeris object.
13-Apr-2026 12:20:40 PASSED
13-Apr-2026 12:20:40
13-Apr-2026 12:20:40 test_task_sdcal.py::sdcal_test_apply::test_apply_composite00 2026-04-13 16:20:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:40 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_ps_070.tab: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_070.tab as mapped, and will be flagged.
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_070.tab as mapped, and will be flagged.
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_070.tab as mapped, and will be flagged.
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_070.tab as mapped, and will be flagged.
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:40 2026-04-13 16:20:40        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:40
13-Apr-2026 12:20:40 test_apply_composite00 --- on-the-fly application of sky table ('ps,apply').
13-Apr-2026 12:20:40 Interpolation option: linear nearestflag
13-Apr-2026 12:20:40 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:40 PASSED
13-Apr-2026 12:20:40
13-Apr-2026 12:20:40 test_task_sdcal.py::sdcal_test_apply::test_apply_composite01 2026-04-13 16:20:41        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:40 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
13-Apr-2026 12:20:40 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_ps_037.tab: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_037.tab as mapped, and will be flagged.
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_037.tab as mapped, and will be flagged.
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_037.tab as mapped, and will be flagged.
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_037.tab as mapped, and will be flagged.
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:40 2026-04-13 16:20:41        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:41
13-Apr-2026 12:20:41 test_apply_composite01 --- on-the-fly application of sky table with existing Tsys table.
13-Apr-2026 12:20:41 Interpolation option: linear nearestflag
13-Apr-2026 12:20:41 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:41 PASSED
13-Apr-2026 12:20:41
13-Apr-2026 12:20:41 test_task_sdcal.py::sdcal_test_apply::test_apply_composite02 2026-04-13 16:20:41        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:41 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_ps_025.tab: 0 1 2 3 4 5 6 7 8 10 12 13 14 15 16 17 18 19 20
13-Apr-2026 12:20:41 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_tsys_067.tab: 0 2 4 6 8 10 12 13 14 15 16 17 18 19 20
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::::        MS obs=0,spw=9,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_025.tab as mapped, and will be flagged.
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::::        MS obs=0,spw=9,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_025.tab as mapped, and will be flagged.
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::::        MS obs=0,spw=11,ant=2 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_025.tab as mapped, and will be flagged.
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::::        MS obs=0,spw=11,ant=3 is selected for processing, but has no available calibration in __sdcal_composite_mode_ps_025.tab as mapped, and will be flagged.
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:41 2026-04-13 16:20:41        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:41
13-Apr-2026 12:20:41 test_apply_composite02 --- on-the-fly application of sky and tsys tables ('ps,tsys,apply').
13-Apr-2026 12:20:41 Interpolation option: linear nearestflag
13-Apr-2026 12:20:41 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:41 PASSED
13-Apr-2026 12:20:41
13-Apr-2026 12:20:41 test_task_sdcal.py::sdcal_test_apply::test_apply_composite03 2026-04-13 16:20:42        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:41 2026-04-13 16:20:42        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:41 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
13-Apr-2026 12:20:41 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_tsys_097.tab: 0 2 4 6 8 10 12 13 14 15 16 17 18 19 20
13-Apr-2026 12:20:41 2026-04-13 16:20:42        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.
13-Apr-2026 12:20:41 2026-04-13 16:20:42        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.
13-Apr-2026 12:20:41 2026-04-13 16:20:42        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.
13-Apr-2026 12:20:41 2026-04-13 16:20:42        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.
13-Apr-2026 12:20:41 2026-04-13 16:20:42        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:41 2026-04-13 16:20:42        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:41 2026-04-13 16:20:42        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:41 2026-04-13 16:20:42        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:41
13-Apr-2026 12:20:41 test_apply_composite03 --- on-the-fly application of sky table ('otfraster,apply').
13-Apr-2026 12:20:41 Interpolation option: linear nearestflag
13-Apr-2026 12:20:41 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:41 PASSED
13-Apr-2026 12:20:41
13-Apr-2026 12:20:41 test_task_sdcal.py::sdcal_test_apply::test_apply_sky00 2026-04-13 16:20:42        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 78, in sdcal
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa+            raise Exception('Applytable name must be specified.')
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa+        Exception: Applytable name must be specified.
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa        Applytable name must be specified.
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Applytable name must be specified.
13-Apr-2026 12:20:42
13-Apr-2026 12:20:42 test_apply_sky00 --- empty applytable.
13-Apr-2026 12:20:42 PASSED
13-Apr-2026 12:20:42
13-Apr-2026 12:20:42 test_task_sdcal.py::sdcal_test_apply::test_apply_sky01 2026-04-13 16:20:42        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 78, in sdcal
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa+            raise Exception('Applytable name must be specified.')
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa+        Exception: Applytable name must be specified.
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa        Applytable name must be specified.
13-Apr-2026 12:20:42 2026-04-13 16:20:42        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Applytable name must be specified.
13-Apr-2026 12:20:42
13-Apr-2026 12:20:42 test_apply_sky01 --- empty applytable (list ver.).
13-Apr-2026 12:20:42 PASSED
13-Apr-2026 12:20:42
13-Apr-2026 12:20:42 test_task_sdcal.py::sdcal_test_apply::test_apply_sky02 2026-04-13 16:20:42        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 72, in sdcal
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+            raise Exception('Applytable name must be specified.')
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+        Exception: Applytable name must be specified.
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Applytable name must be specified.
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Applytable name must be specified.
13-Apr-2026 12:20:42
13-Apr-2026 12:20:42 test_apply_sky02 --- empty applytable list.
13-Apr-2026 12:20:42 PASSED
13-Apr-2026 12:20:42
13-Apr-2026 12:20:42 test_task_sdcal.py::sdcal_test_apply::test_apply_sky03 2026-04-13 16:20:43        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 81, in sdcal
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+            raise Exception("Table doesn't exist: {}".format(_table_name))
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+        Exception: Table doesn't exist: notexist.sky
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Table doesn't exist: notexist.sky
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Table doesn't exist: notexist.sky
13-Apr-2026 12:20:42
13-Apr-2026 12:20:42 test_apply_sky03 --- unexisting applytable.
13-Apr-2026 12:20:42 PASSED
13-Apr-2026 12:20:42
13-Apr-2026 12:20:42 test_task_sdcal.py::sdcal_test_apply::test_apply_sky04 2026-04-13 16:20:43        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 81, in sdcal
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+            raise Exception("Table doesn't exist: {}".format(_table_name))
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa+        Exception: Table doesn't exist: notexist.sky
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Table doesn't exist: notexist.sky
13-Apr-2026 12:20:42 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Task sdcal raised an exception of class Exception with the following message: Table doesn't exist: notexist.sky
13-Apr-2026 12:20:42
13-Apr-2026 12:20:42 test_apply_sky04 --- unexisting applytable (list ver.).
13-Apr-2026 12:20:42 PASSED
13-Apr-2026 12:20:42
13-Apr-2026 12:20:43 test_task_sdcal.py::sdcal_test_apply::test_apply_sky05 2026-04-13 16:20:43        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::Calibrater::selectvis (file /source/casa6/casatools/src/code/synthesis/MeasurementComponents/Calibrater.cc, line 4221)        Caught exception: Spw Expression: No match found for 99,
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::        Exception Reported: Error in data selection specification: Spw Expression: No match found for 99,
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 46, in sdcal
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+            cb.selectvis(spw=spw, scan=scan, field=field)
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/calibrater.py", line 82, in selectvis
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+            return self._swigobj.selectvis(time, spw, scan, field, intent, observation, baseline, uvrange, chanmode, nchan, start, step, mstart, mstep, msselect)
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/__casac__/calibrater.py", line 264, in selectvis
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+            return _calibrater.calibrater_selectvis(self, *args, **kwargs)
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+        RuntimeError: Error in data selection specification: Spw Expression: No match found for 99,
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Error in data selection specification: Spw Expression: No match found for 99,
13-Apr-2026 12:20:43 2026-04-13 16:20:43        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,
13-Apr-2026 12:20:43
13-Apr-2026 12:20:43 test_apply_sky05 --- invalid selection (empty selection result).
13-Apr-2026 12:20:43 PASSED
13-Apr-2026 12:20:43
13-Apr-2026 12:20:43 test_task_sdcal.py::sdcal_test_apply::test_apply_sky06 2026-04-13 16:20:43        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::Calibrater::setapply(type, applypar)        Unknown interp type: 'sinusoid'!! Check inputs and try again.
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::        Exception Reported: Error in Calibrater::setapply.
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 121, in sdcal
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+            cb.setapply(table=_table, interp=interp, calwt=True)
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/calibrater.py", line 212, in setapply
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+            return self._swigobj.setapply(type, t, table, field, interp, select, calwt, spwmap, opacity)
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatools/__casac__/calibrater.py", line 479, in setapply
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+            return _calibrater.calibrater_setapply(self, *args, **kwargs)
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa+        RuntimeError: Error in Calibrater::setapply.
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Error in Calibrater::setapply.
13-Apr-2026 12:20:43 2026-04-13 16:20:43        SEVERE        sdcal::::casa        Task sdcal raised an exception of class RuntimeError with the following message: Error in Calibrater::setapply.
13-Apr-2026 12:20:43
13-Apr-2026 12:20:43 test_apply_sky06 --- invalid interp value.
13-Apr-2026 12:20:43 PASSED
13-Apr-2026 12:20:43
13-Apr-2026 12:20:43 test_task_sdcal.py::sdcal_test_apply::test_apply_sky07 2026-04-13 16:20:44        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:43 2026-04-13 16:20:44        SEVERE        sdcal::::casa        Traceback (most recent call last):
13-Apr-2026 12:20:43 2026-04-13 16:20:44        SEVERE        sdcal::::casa+                     ^^^^^^^^^^^^^^^^^^^^^
13-Apr-2026 12:20:43 2026-04-13 16:20:44        SEVERE        sdcal::::casa+          File "/home/casatest/venv/lib/python3.12/site-packages/casatasks/private/task_sdcal.py", line 116, in sdcal
13-Apr-2026 12:20:43 2026-04-13 16:20:44        SEVERE        sdcal::::casa+            raise RuntimeError('Applytable \'%s\' is not a caltable format' % (_table))
13-Apr-2026 12:20:43 2026-04-13 16:20:44        SEVERE        sdcal::::casa+        RuntimeError: Applytable 'uid___A002_X6218fb_X264.ms.sel' is not a caltable format
13-Apr-2026 12:20:43 2026-04-13 16:20:44        SEVERE        sdcal::::casa        Applytable 'uid___A002_X6218fb_X264.ms.sel' is not a caltable format
13-Apr-2026 12:20:43 2026-04-13 16:20:44        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
13-Apr-2026 12:20:43
13-Apr-2026 12:20:43 test_apply_sky07 --- invalid applytable (not caltable).
13-Apr-2026 12:20:43 PASSED
13-Apr-2026 12:20:43
13-Apr-2026 12:20:43 test_task_sdcal.py::sdcal_test_apply::test_apply_sky08 2026-04-13 16:20:44        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:43 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
13-Apr-2026 12:20:43 2026-04-13 16:20:44        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.
13-Apr-2026 12:20:43 2026-04-13 16:20:44        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.
13-Apr-2026 12:20:43 2026-04-13 16:20:44        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.
13-Apr-2026 12:20:43 2026-04-13 16:20:44        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.
13-Apr-2026 12:20:43 2026-04-13 16:20:44        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:43 2026-04-13 16:20:44        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:43 2026-04-13 16:20:44        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:43 2026-04-13 16:20:44        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:43
13-Apr-2026 12:20:43 test_apply_sky08 --- apply data (linear).
13-Apr-2026 12:20:43 Interpolation option: linear nearestflag
13-Apr-2026 12:20:43 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:43 PASSED
13-Apr-2026 12:20:43
13-Apr-2026 12:20:44 test_task_sdcal.py::sdcal_test_apply::test_apply_sky08M
13-Apr-2026 12:20:44 test_apply_sky08M --- apply data (linear) for MMS.
13-Apr-2026 12:20:44 SKIPPED (Skip test_apply_sky08M until calibrator tool supports proce...)
13-Apr-2026 12:20:44
13-Apr-2026 12:20:44 test_task_sdcal.py::sdcal_test_apply::test_apply_sky09 2026-04-13 16:20:44        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20: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
13-Apr-2026 12:20:44 2026-04-13 16:20:44        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.
13-Apr-2026 12:20:44 2026-04-13 16:20:44        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.
13-Apr-2026 12:20:44
13-Apr-2026 12:20:44 test_apply_sky09 --- apply selected data.
13-Apr-2026 12:20:44 Interpolation option: linear nearestflag
13-Apr-2026 12:20:44 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:44 PASSED
13-Apr-2026 12:20:44
13-Apr-2026 12:20:44 test_task_sdcal.py::sdcal_test_apply::test_apply_sky10 2026-04-13 16:20:44        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20: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
13-Apr-2026 12:20:44 2026-04-13 16:20: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.
13-Apr-2026 12:20:44 2026-04-13 16:20: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.
13-Apr-2026 12:20:44 2026-04-13 16:20: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.
13-Apr-2026 12:20:44 2026-04-13 16:20: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.
13-Apr-2026 12:20:44 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:44 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:44 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:44 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:44
13-Apr-2026 12:20:44 test_apply_sky10 --- apply data (nearest).
13-Apr-2026 12:20:44 Interpolation option: nearest nearestflag
13-Apr-2026 12:20:44 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:44 PASSED
13-Apr-2026 12:20:44
13-Apr-2026 12:20:44 test_task_sdcal.py::sdcal_test_apply::test_apply_sky11 2026-04-13 16:20:45        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20: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
13-Apr-2026 12:20:44 2026-04-13 16:20: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.
13-Apr-2026 12:20:44 2026-04-13 16:20: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.
13-Apr-2026 12:20:44 2026-04-13 16:20: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.
13-Apr-2026 12:20:44 2026-04-13 16:20: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.
13-Apr-2026 12:20:44 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:44 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:44 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:44 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:45
13-Apr-2026 12:20:45 test_apply_sky11 --- apply data (linearflag for frequency interpolation).
13-Apr-2026 12:20:45 Interpolation option: linear nearestflag
13-Apr-2026 12:20:45 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:45 PASSED
13-Apr-2026 12:20:45
13-Apr-2026 12:20:45 test_task_sdcal.py::sdcal_test_apply::test_apply_sky12 2026-04-13 16:20:45        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:45 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
13-Apr-2026 12:20:45 2026-04-13 16:20: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.
13-Apr-2026 12:20:45 2026-04-13 16:20: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.
13-Apr-2026 12:20:45 2026-04-13 16:20: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.
13-Apr-2026 12:20:45 2026-04-13 16:20: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.
13-Apr-2026 12:20:45 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:45 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:45 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:45 2026-04-13 16:20:45        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:45
13-Apr-2026 12:20:45 test_apply_sky12 --- apply data (nearestflag for frequency interpolation).
13-Apr-2026 12:20:45 Interpolation option: linear nearestflag
13-Apr-2026 12:20:45 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:45 PASSED
13-Apr-2026 12:20:45
13-Apr-2026 12:20:45 test_task_sdcal.py::sdcal_test_apply::test_apply_sky13 2026-04-13 16:20:45        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:45 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
13-Apr-2026 12:20:45 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:45 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:45 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:45
13-Apr-2026 12:20:45 test_apply_sky13 --- apply data (string applytable input).
13-Apr-2026 12:20:45 Interpolation option: linear nearestflag
13-Apr-2026 12:20:45 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:45 PASSED
13-Apr-2026 12:20:45
13-Apr-2026 12:20:45 test_task_sdcal.py::sdcal_test_apply::test_apply_sky14 2026-04-13 16:20:46        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:45 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
13-Apr-2026 12:20:45 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:45 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:45 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:45 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 test_apply_sky14 --- apply data (interp='').
13-Apr-2026 12:20:46 Interpolation option: linear nearestflag
13-Apr-2026 12:20:46 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:46 PASSED
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 test_task_sdcal.py::sdcal_test_apply::test_apply_sky15 2026-04-13 16:20:46        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:46 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
13-Apr-2026 12:20:46 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:46 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:46 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:46 2026-04-13 16:20:46        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.
13-Apr-2026 12:20:46 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 1, 3,
13-Apr-2026 12:20:46 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:46 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:46 2026-04-13 16:20:46        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 test_apply_sky15 --- check if WEIGHT_SPECTRUM is updated properly when it exists.
13-Apr-2026 12:20:46 Interpolation option: linear nearestflag
13-Apr-2026 12:20:46 self.finterp: interp_freq_nearestflag
13-Apr-2026 12:20:46 PASSED
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 test_task_sdcal.py::sdcal_test_single_polarization::test_single_pol_apply 2026-04-13 16:20:46        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:46 The following MS spws have no corresponding cal spws in uid___A002_X6218fb_X264.ms.sel.sky: 0
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 0,
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 test_single_pol_apply --- apply caltable to single-polarization data.
13-Apr-2026 12:20:46 PASSED
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 test_task_sdcal.py::sdcal_test_single_polarization::test_single_pol_apply_composite 2026-04-13 16:20:47        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:46 The following MS spws have no corresponding cal spws in __sdcal_composite_mode_ps_039.tab: 0
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::Calibrater::correct2 (VI2/VB2)        Spectral window(s) 0,
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+          could not be corrected due to missing (pre-)calibration
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            in one or more of the specified tables.
13-Apr-2026 12:20:46 2026-04-13 16:20:47        WARN        sdcal::Calibrater::correct2 (VI2/VB2)+            Please check your results carefully!
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 test_single_pol_apply_composite --- on-the-fly calibration/application on single-polarization data.
13-Apr-2026 12:20:46 PASSED
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 test_task_sdcal.py::sdcal_test_single_polarization::test_single_pol_ps 2026-04-13 16:20:47        WARN        sdcal::calibrater::setvi(bool,bool)        Already using OLD VisibilityIterator.
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 test_single_pol_ps --- generate caltable for single-polarization data.
13-Apr-2026 12:20:46 PASSED
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_task_sdcal/nose.xml -
13-Apr-2026 12:20:46 =========================== short test summary info ============================
13-Apr-2026 12:20:46 SKIPPED [1] test_task_sdcal.py:1994: Skip test_apply_sky08M until calibrator tool supports processing MMS on serial casa
13-Apr-2026 12:20:46 ================= 65 passed, 1 skipped, 13 warnings in 27.39s ==================
13-Apr-2026 12:20:46 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,/home/casatest/casa6/casatasks/tests/scripts', test_list=None, test_config=None, test_group='sdcal', pmode=None, bamboo=False, cachedir=None, ignore_list='test_casampi,test_task_msview,test_task_imview,test_task_plotms', run_skipped=False, only_skipped=False)
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 Operating system: Linux
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 Testing Components['sdcal']
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 Arguments Sent Direct To Pytest :  []
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 Tests to Ignore:  ['test_casampi', 'test_task_msview', 'test_task_imview', 'test_task_plotms']
13-Apr-2026 12:20:46 Running 1 Test(s)
13-Apr-2026 12:20:46 Cleaning: /home/casatest/casa6/casatestutils/nosedir/
13-Apr-2026 12:20:46 Tests: ['/home/casatest/casa6/casatasks/tests/tasks/test_task_sdcal.py']
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdcal/
13-Apr-2026 12:20:46 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_task_sdcal
13-Apr-2026 12:20:46
13-Apr-2026 12:20:46 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/
13-Apr-2026 12:20:47 total 78792
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci 15001  1885411 Apr 13 16:18 casatasks-6.7.4.1a14752.dev1-py3-none-any.whl
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci root     87924 Apr 13 16:20 casatasks_test.log
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci 15001   137830 Apr 13 16:18 casatestutils-6.7.4.1a14752.dev1-py3-none-any.whl
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci 15001 78552235 Apr 13 16:18 casatools-6.7.4.1a14752.dev1-cp312-cp312-manylinux_2_28_x86_64.whl
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci root       906 Apr 13 16:20 requirements.txt
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci root      6646 Apr 13 16:20 test_task_sdcal.xml
13-Apr-2026 12:20:47 Finished task 'Test wheel' with result: Success
13-Apr-2026 12:20:47 Starting task 'Inject is_641' of type 'com.atlassian.bamboo.plugins.bamboo-variable-inject-plugin:inject'
13-Apr-2026 12:20:47 Injected variable bamboo.inject.is_641=False in LOCAL scope
13-Apr-2026 12:20:47 Finished task 'Inject is_641' with result: Success
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47 Starting task 'Tar work directory' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
13-Apr-2026 12:20:47
Beginning to execute external process for build 'CASASPECS - Dev Test 3.12 - CAS-14752 - Test Tasks Many Linux 2.28 Py3.12 #1 (CASASPECS-DEVTESTPY31297-TTM228PY312-1)'
... running command line:
/opt/ci_workspace/casa/bamboo/agent7/temp/CASASPECS-DEVTESTPY31297-TTM228PY312-1-ScriptBuildTask-14036549802038567003.sh
... in: /opt/ci_workspace/casa/bamboo/agent7/xml-data/build-dir/CASASPECS-DEVTESTPY31297-TTM228PY312
13-Apr-2026 12:20:47 Package working directory
13-Apr-2026 12:20:47 nosedir/
13-Apr-2026 12:20:47 nosedir/test_task_sdcal/
13-Apr-2026 12:20:47 nosedir/test_task_sdcal/test_task_sdcal.py
13-Apr-2026 12:20:47 nosedir/test_task_sdcal/conftest.py
13-Apr-2026 12:20:47 nosedir/test_task_sdcal/casa-20260413-162019.log
13-Apr-2026 12:20:47 nosedir/test_task_sdcal/short_summary.log
13-Apr-2026 12:20:47 nosedir/xml/
13-Apr-2026 12:20:47 nosedir/xml/test_task_sdcal/
13-Apr-2026 12:20:47 nosedir/xml/test_task_sdcal/nose.xml
13-Apr-2026 12:20:47 nosedir/summary_of_failed.log
13-Apr-2026 12:20:47 nosedir/requirements.txt
13-Apr-2026 12:20:47 total 78832
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci 15001  1885411 Apr 13 16:18 casatasks-6.7.4.1a14752.dev1-py3-none-any.whl
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci root     87924 Apr 13 16:20 casatasks_test.log
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci 15001   137830 Apr 13 16:18 casatestutils-6.7.4.1a14752.dev1-py3-none-any.whl
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci 15001 78552235 Apr 13 16:18 casatools-6.7.4.1a14752.dev1-cp312-cp312-manylinux_2_28_x86_64.whl
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci root       906 Apr 13 16:20 requirements.txt
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci root      6646 Apr 13 16:20 test_task_sdcal.xml
13-Apr-2026 12:20:47 -rw-r--r-- 1 casaci root     38846 Apr 13 16:20 work-manylinux_228_casatasks-3.12.tar.gz
13-Apr-2026 12:20:47 Done packaging working directory
13-Apr-2026 12:20:47 Finished task 'Tar work directory' with result: Success
13-Apr-2026 12:20:47 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
13-Apr-2026 12:20:47 Parsing test results under /opt/ci_workspace/casa/bamboo/agent7/xml-data/build-dir/CASASPECS-DEVTESTPY31297-TTM228PY312...
13-Apr-2026 12:20:47 Finished task 'Parse tests' with result: Success
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.repository.branch.name} with CAS-14752
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.planRepository.branchName} with CAS-14752
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:47 Starting task 'Upload wheel' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
13-Apr-2026 12:20:47
Beginning to execute external process for build 'CASASPECS - Dev Test 3.12 - CAS-14752 - Test Tasks Many Linux 2.28 Py3.12 #1 (CASASPECS-DEVTESTPY31297-TTM228PY312-1)'
... running command line:
/opt/ci_workspace/casa/bamboo/agent7/temp/CASASPECS-DEVTESTPY31297-TTM228PY312-1-ScriptBuildTask-1606118463067053174.sh
... in: /opt/ci_workspace/casa/bamboo/agent7/xml-data/build-dir/CASASPECS-DEVTESTPY31297-TTM228PY312
13-Apr-2026 12:20:48 Ticket fix version: CASA 6.7.4
13-Apr-2026 12:20:48 No matching version found
13-Apr-2026 12:20:48 Collecting twine
13-Apr-2026 12:20:48   Downloading twine-6.2.0-py3-none-any.whl (42 kB)
13-Apr-2026 12:20:48      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 42.7/42.7 kB 7.5 MB/s eta 0:00:00
13-Apr-2026 12:20:48 Collecting rfc3986>=1.4.0
13-Apr-2026 12:20:48   Downloading rfc3986-2.0.0-py2.py3-none-any.whl (31 kB)
13-Apr-2026 12:20:48 Collecting urllib3>=1.26.0
13-Apr-2026 12:20:48   Downloading urllib3-2.6.3-py3-none-any.whl (131 kB)
13-Apr-2026 12:20:48      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 131.6/131.6 kB 24.1 MB/s eta 0:00:00
13-Apr-2026 12:20:48 Collecting id
13-Apr-2026 12:20:48   Downloading id-1.6.1-py3-none-any.whl (14 kB)
13-Apr-2026 12:20:48 Collecting packaging>=24.0
13-Apr-2026 12:20:48   Downloading packaging-26.0-py3-none-any.whl (74 kB)
13-Apr-2026 12:20:48      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 32.0 MB/s eta 0:00:00
13-Apr-2026 12:20:48 Collecting keyring>=21.2.0
13-Apr-2026 12:20:48   Downloading keyring-25.7.0-py3-none-any.whl (39 kB)
13-Apr-2026 12:20:48 Collecting requests-toolbelt!=0.9.0,>=0.8.0
13-Apr-2026 12:20:48   Downloading requests_toolbelt-1.0.0-py2.py3-none-any.whl (54 kB)
13-Apr-2026 12:20:48      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 54.5/54.5 kB 31.2 MB/s eta 0:00:00
13-Apr-2026 12:20:48 Collecting rich>=12.0.0
13-Apr-2026 12:20:48   Downloading rich-15.0.0-py3-none-any.whl (310 kB)
13-Apr-2026 12:20:48      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 310.7/310.7 kB 68.2 MB/s eta 0:00:00
13-Apr-2026 12:20:48 Collecting requests>=2.20
13-Apr-2026 12:20:48   Downloading requests-2.33.1-py3-none-any.whl (64 kB)
13-Apr-2026 12:20:48      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 64.9/64.9 kB 30.8 MB/s eta 0:00:00
13-Apr-2026 12:20:48 Collecting readme-renderer>=35.0
13-Apr-2026 12:20:48   Downloading readme_renderer-44.0-py3-none-any.whl (13 kB)
13-Apr-2026 12:20:48 Collecting jeepney>=0.4.2
13-Apr-2026 12:20:48   Downloading jeepney-0.9.0-py3-none-any.whl (49 kB)
13-Apr-2026 12:20:48      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 49.0/49.0 kB 22.5 MB/s eta 0:00:00
13-Apr-2026 12:20:48 Collecting importlib_metadata>=4.11.4
13-Apr-2026 12:20:48   Downloading importlib_metadata-9.0.0-py3-none-any.whl (27 kB)
13-Apr-2026 12:20:48 Collecting jaraco.functools
13-Apr-2026 12:20:48   Downloading jaraco_functools-4.4.0-py3-none-any.whl (10 kB)
13-Apr-2026 12:20:48 Collecting jaraco.context
13-Apr-2026 12:20:48   Downloading jaraco_context-6.1.2-py3-none-any.whl (7.9 kB)
13-Apr-2026 12:20:48 Collecting jaraco.classes
13-Apr-2026 12:20:48   Downloading jaraco.classes-3.4.0-py3-none-any.whl (6.8 kB)
13-Apr-2026 12:20:48 Collecting SecretStorage>=3.2
13-Apr-2026 12:20:48   Downloading secretstorage-3.5.0-py3-none-any.whl (15 kB)
13-Apr-2026 12:20:48 Collecting docutils>=0.21.2
13-Apr-2026 12:20:48   Downloading docutils-0.22.4-py3-none-any.whl (633 kB)
13-Apr-2026 12:20:48      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 633.2/633.2 kB 101.7 MB/s eta 0:00:00
13-Apr-2026 12:20:49 Collecting nh3>=0.2.14
13-Apr-2026 12:20:49   Downloading nh3-0.3.4-cp38-abi3-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (804 kB)
13-Apr-2026 12:20:49      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 805.0/805.0 kB 143.7 MB/s eta 0:00:00
13-Apr-2026 12:20:49 Collecting Pygments>=2.5.1
13-Apr-2026 12:20:49   Downloading pygments-2.20.0-py3-none-any.whl (1.2 MB)
13-Apr-2026 12:20:49      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 146.5 MB/s eta 0:00:00
13-Apr-2026 12:20:49 Collecting certifi>=2023.5.7
13-Apr-2026 12:20:49   Downloading certifi-2026.2.25-py3-none-any.whl (153 kB)
13-Apr-2026 12:20:49      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 153.7/153.7 kB 56.9 MB/s eta 0:00:00
13-Apr-2026 12:20:49 Collecting charset_normalizer<4,>=2
13-Apr-2026 12:20:49   Downloading charset_normalizer-3.4.7-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (216 kB)
13-Apr-2026 12:20:49      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 216.9/216.9 kB 63.0 MB/s eta 0:00:00
13-Apr-2026 12:20:49 Collecting idna<4,>=2.5
13-Apr-2026 12:20:49   Downloading idna-3.11-py3-none-any.whl (71 kB)
13-Apr-2026 12:20:49      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 71.0/71.0 kB 32.6 MB/s eta 0:00:00
13-Apr-2026 12:20:49 Collecting markdown-it-py>=2.2.0
13-Apr-2026 12:20:49   Downloading markdown_it_py-4.0.0-py3-none-any.whl (87 kB)
13-Apr-2026 12:20:49      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 87.3/87.3 kB 3.9 MB/s eta 0:00:00
13-Apr-2026 12:20:49 Collecting zipp>=3.20
13-Apr-2026 12:20:49   Downloading zipp-3.23.0-py3-none-any.whl (10 kB)
13-Apr-2026 12:20:49 Collecting mdurl~=0.1
13-Apr-2026 12:20:49   Downloading mdurl-0.1.2-py3-none-any.whl (10.0 kB)
13-Apr-2026 12:20:49 Collecting cryptography>=2.0
13-Apr-2026 12:20:49   Downloading cryptography-46.0.7-cp38-abi3-manylinux_2_28_x86_64.whl (4.5 MB)
13-Apr-2026 12:20:49      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 180.2 MB/s eta 0:00:00
13-Apr-2026 12:20:49 Collecting more-itertools
13-Apr-2026 12:20:49   Downloading more_itertools-11.0.2-py3-none-any.whl (71 kB)
13-Apr-2026 12:20:49      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 71.9/71.9 kB 37.4 MB/s eta 0:00:00
13-Apr-2026 12:20:49 Collecting backports.tarfile
13-Apr-2026 12:20:49   Downloading backports.tarfile-1.2.0-py3-none-any.whl (30 kB)
13-Apr-2026 12:20:50 Collecting cffi>=2.0.0
13-Apr-2026 12:20:50   Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB)
13-Apr-2026 12:20:50      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 216.5/216.5 kB 65.9 MB/s eta 0:00:00
13-Apr-2026 12:20:50 Collecting typing-extensions>=4.13.2
13-Apr-2026 12:20:50   Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB)
13-Apr-2026 12:20:50      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 14.2 MB/s eta 0:00:00
13-Apr-2026 12:20:50 Collecting pycparser
13-Apr-2026 12:20:50   Downloading pycparser-3.0-py3-none-any.whl (48 kB)
13-Apr-2026 12:20:50      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 48.2/48.2 kB 29.1 MB/s eta 0:00:00
13-Apr-2026 12:20:50 Installing collected packages: zipp, urllib3, typing-extensions, rfc3986, Pygments, pycparser, packaging, nh3, 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, id, cffi, rich, requests-toolbelt, cryptography, SecretStorage, keyring, twine
13-Apr-2026 12:20:51 Successfully installed Pygments-2.20.0 SecretStorage-3.5.0 backports.tarfile-1.2.0 certifi-2026.2.25 cffi-2.0.0 charset_normalizer-3.4.7 cryptography-46.0.7 docutils-0.22.4 id-1.6.1 idna-3.11 importlib_metadata-9.0.0 jaraco.classes-3.4.0 jaraco.context-6.1.2 jaraco.functools-4.4.0 jeepney-0.9.0 keyring-25.7.0 markdown-it-py-4.0.0 mdurl-0.1.2 more-itertools-11.0.2 nh3-0.3.4 packaging-26.0 pycparser-3.0 readme-renderer-44.0 requests-2.33.1 requests-toolbelt-1.0.0 rfc3986-2.0.0 rich-15.0.0 twine-6.2.0 typing-extensions-4.15.0 urllib3-2.6.3 zipp-3.23.0
13-Apr-2026 12:20:51 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
13-Apr-2026 12:20:51
13-Apr-2026 12:20:51 [notice] A new release of pip is available: 23.0.1 -> 26.0.1
13-Apr-2026 12:20:51 [notice] To update, run: pip3 install --upgrade pip
13-Apr-2026 12:20:52 Cloning into 'casa-build-utils'...
13-Apr-2026 12:20:52 Uploading distributions to
13-Apr-2026 12:20:52 https://casa-pip.nrao.edu:443/repository/casa-dev-wheel/
13-Apr-2026 12:20:52 Uploading casatasks-6.7.4.1a14752.dev1-py3-none-any.whl
13-Apr-2026 12:20:52
13-Apr-2026 12:20:52   0% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 0.0/1.9 MB--:--?
13-Apr-2026 12:20:52 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.9/1.9 MB00:00444.5 MB/s
13-Apr-2026 12:20:52 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.9/1.9 MB00:00444.5 MB/s
13-Apr-2026 12:20:52 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.9/1.9 MB00:00444.5 MB/s
13-Apr-2026 12:20:52 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.9/1.9 MB00:00444.5 MB/s
13-Apr-2026 12:20:52 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.9/1.9 MB00:00444.5 MB/s
13-Apr-2026 12:20:52 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.9/1.9 MB00:00444.5 MB/s
13-Apr-2026 12:20:53
13-Apr-2026 12:20:53 Finished task 'Upload wheel' with result: Success
13-Apr-2026 12:20:53
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:53
Substituting variable: ${bamboo.agentId} with 153550855
13-Apr-2026 12:20:53 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
13-Apr-2026 12:20:53
Beginning to execute external process for build 'CASASPECS - Dev Test 3.12 - CAS-14752 - Test Tasks Many Linux 2.28 Py3.12 #1 (CASASPECS-DEVTESTPY31297-TTM228PY312-1)'
... running command line:
/opt/ci_workspace/casa/bamboo/agent7/temp/CASASPECS-DEVTESTPY31297-TTM228PY312-1-ScriptBuildTask-7666066117373449441.sh
... in: /opt/ci_workspace/casa/bamboo/agent7/xml-data/build-dir/CASASPECS-DEVTESTPY31297-TTM228PY312
13-Apr-2026 12:20:53 Starting cleanup
13-Apr-2026 12:21:03 wheel-container-test_153550855
13-Apr-2026 12:21:05 wheel-container-test_153550855
13-Apr-2026 12:21:05 Cleanup complete
13-Apr-2026 12:21:05 Finished task 'Cleanup' with result: Success
13-Apr-2026 12:21:05 Running post build plugin 'Docker Container Cleanup'
13-Apr-2026 12:21:05
Beginning to execute external process for build 'CASASPECS - Dev Test 3.12 - CAS-14752 - Test Tasks Many Linux 2.28 Py3.12 #1 (CASASPECS-DEVTESTPY31297-TTM228PY312-1)'
... running command line:
/usr/bin/docker rm -f wheel-container-test_153550855
... in: /opt/ci_workspace/casa/bamboo/agent7/xml-data/build-dir/CASASPECS-DEVTESTPY31297-TTM228PY312
13-Apr-2026 12:21:05 Error response from daemon: No such container: wheel-container-test_153550855
13-Apr-2026 12:21:05 Running post build plugin 'NCover Results Collector'
13-Apr-2026 12:21:05 Running post build plugin 'Build Results Label Collector'
13-Apr-2026 12:21:05 Running post build plugin 'Clover Results Collector'
13-Apr-2026 12:21:05 Running post build plugin 'npm Cache Cleanup'
13-Apr-2026 12:21:05 Running post build plugin 'Artifact Copier'
13-Apr-2026 12:21:05 Publishing an artifact: Workdir
13-Apr-2026 12:21:05 Finished publishing of artifact Non required job artifact Http Compression On : [Workdir], patterns: [work*.tar.gz] anchored at: [wheeldirectory/] in 35.24 ms
13-Apr-2026 12:21:05 Publishing an artifact: XML Directories
13-Apr-2026 12:21:05 Finished publishing of artifact Non required job artifact Http Compression On : [XML Directories], patterns: [**/*.xml] anchored at: [wheeldirectory/] in 9.220 ms
13-Apr-2026 12:21:05 Publishing an artifact: casatasks_test.log
13-Apr-2026 12:21:05 Finished publishing of artifact Non required job artifact Http Compression On : [casatasks_test.log], patterns: [casatasks_test.log] anchored at: [wheeldirectory/] in 18.51 ms
13-Apr-2026 12:21:05 Publishing an artifact: requirements.txt
13-Apr-2026 12:21:05 Finished publishing of artifact Non required job artifact Http Compression On : [requirements.txt], patterns: [requirements.txt] anchored at: [wheeldirectory/] in 8.543 ms
13-Apr-2026 12:21:05 Finalising the build...
13-Apr-2026 12:21:05 Stopping timer.
13-Apr-2026 12:21:05 Build CASASPECS-DEVTESTPY31297-TTM228PY312-1 completed.
13-Apr-2026 12:21:05 Running on server: post build plugin 'Build Hanging Detection Configuration'
13-Apr-2026 12:21:05 Running on server: post build plugin 'NCover Results Collector'
13-Apr-2026 12:21:05 Running on server: post build plugin 'Build Labeller'
13-Apr-2026 12:21:05 Running on server: post build plugin 'Clover Delta Calculator'
13-Apr-2026 12:21:05 Running on server: post build plugin 'Maven Dependencies Postprocessor'
13-Apr-2026 12:21:05 All post build plugins have finished
13-Apr-2026 12:21:05 Generating build results summary...
13-Apr-2026 12:21:05 Saving build results to disk...
13-Apr-2026 12:21:05 Store variable context...
13-Apr-2026 12:21:05 Finished building CASASPECS-DEVTESTPY31297-TTM228PY312-1.