Build: #1 was successful
Job: Test Tools Many Linux 2.28 Python3.12 was successful
Job result summary
- Completed
- Duration
- 34 minutes
- Agent
- cbt-el7-10.cv.nrao.edu
- Revision
-
efc4da3707a15600c2d41bb6752985c2e553f992
- Total tests
- 695
Tests
- 695 tests in total
- 5 tests were quarantined / skipped
- 22 minutes taken in total.
Error summary
The build generated some errors. See the full build log for more details.
Downloading measurementset/alma/split11.ms/HISTORY/table.dat (2.6 KB)
Checking out files: 49% (78/158)
Downloading measurementset/alma/split11.ms/HISTORY/table.f0 (17 KB)
Checking out files: 50% (79/158)
Downloading measurementset/alma/split11.ms/HISTORY/table.lock (325 B)
Checking out files: 51% (81/158)
Downloading measurementset/alma/split11.ms/OBSERVATION/table.f0 (9.7 KB)
Checking out files: 52% (83/158)
Checking out files: 53% (84/158)
Checking out files: 54% (86/158)
Checking out files: 55% (87/158)
Downloading measurementset/alma/split11.ms/POINTING/table.f1 (33 KB)
Checking out files: 56% (89/158)
Checking out files: 57% (91/158)
Checking out files: 58% (92/158)
Checking out files: 59% (94/158)
Checking out files: 60% (95/158)
Checking out files: 61% (97/158)
Checking out files: 62% (98/158)
Checking out files: 62% (99/158)
Checking out files: 63% (100/158)
Downloading measurementset/alma/split11.ms/SOURCE/table.f0 (20 KB)
Checking out files: 64% (102/158)
Downloading measurementset/alma/split11.ms/SOURCE/table.f0i (80 B)
Checking out files: 65% (103/158)
Checking out files: 66% (105/158)
Checking out files: 67% (106/158)
Downloading measurementset/alma/split11.ms/SPECTRAL_WINDOW/table.f0 (27 KB)
Downloading measurementset/alma/split11.ms/SPECTRAL_WINDOW/table.f0i (33 KB)
Checking out files: 68% (108/158)
Checking out files: 68% (109/158)
Checking out files: 69% (110/158)
Checking out files: 70% (111/158)
Downloading measurementset/alma/split11.ms/STATE/table.f0 (3.6 KB)
Checking out files: 71% (113/158)
Downloading measurementset/alma/split11.ms/STATE/table.lock (325 B)
Checking out files: 72% (114/158)
Downloading measurementset/alma/split11.ms/SYSCAL/table.dat (5.2 KB)
Checking out files: 72% (115/158)
Downloading measurementset/alma/split11.ms/SYSCAL/table.f0 (16 KB)
Checking out files: 73% (116/158)
Downloading measurementset/alma/split11.ms/SYSCAL/table.f0i (1.3 MB)
Checking out files: 74% (117/158)
Checking out files: 75% (119/158)
Checking out files: 76% (121/158)
Checking out files: 77% (122/158)
Checking out files: 78% (124/158)
Downloading measurementset/alma/split11.ms/WEATHER/table.dat (5.3 KB)
Checking out files: 79% (125/158)
Downloading measurementset/alma/split11.ms/WEATHER/table.f0 (16 KB)
Checking out files: 80% (127/158)
Downloading measurementset/alma/split11.ms/WEATHER/table.lock (325 B)
Checking out files: 81% (128/158)
Downloading measurementset/alma/split11.ms/table.dat (11 KB)
Downloading measurementset/alma/split11.ms/table.f1 (33 KB)
Checking out files: 82% (130/158)
Downloading measurementset/alma/split11.ms/table.f10 (33 KB)
Downloading measurementset/alma/split11.ms/table.f11 (33 KB)
Checking out files: 83% (132/158)
Downloading measurementset/alma/split11.ms/table.f12 (33 KB)
Checking out files: 84% (133/158)
Checking out files: 84% (134/158)
Downloading measurementset/alma/split11.ms/table.f14 (66 KB)
Checking out files: 85% (135/158)
Downloading measurementset/alma/split11.ms/table.f15 (66 KB)
Checking out files: 86% (136/158)
Downloading measurementset/alma/split11.ms/table.f16 (66 KB)
Downloading measurementset/alma/split11.ms/table.f17 (483 B)
Checking out files: 87% (138/158)
Downloading measurementset/alma/split11.ms/table.f17_TSM1 (2.1 MB)
Checking out files: 87% (139/158)
Downloading measurementset/alma/split11.ms/table.f18 (331 B)
Checking out files: 88% (140/158)
Downloading measurementset/alma/split11.ms/table.f19 (276 B)
Checking out files: 89% (141/158)
Downloading measurementset/alma/split11.ms/table.f19_TSM0 (1.0 MB)
Downloading measurementset/alma/split11.ms/table.f2 (33 KB)
Checking out files: 90% (143/158)
Downloading measurementset/alma/split11.ms/table.f20 (483 B)
Checking out files: 91% (144/158)
Downloading measurementset/alma/split11.ms/table.f20_TSM1 (131 KB)
Checking out files: 91% (145/158)
Downloading measurementset/alma/split11.ms/table.f21 (470 B)
Checking out files: 92% (146/158)
Downloading measurementset/alma/split11.ms/table.f21_TSM1 (524 KB)
Checking out files: 93% (147/158)
Downloading measurementset/alma/split11.ms/table.f22 (472 B)
Downloading measurementset/alma/split11.ms/table.f22_TSM1 (524 KB)
Checking out files: 94% (149/158)
Checking out files: 95% (151/158)
Downloading measurementset/alma/split11.ms/table.f5 (33 KB)
Checking out files: 96% (152/158)
Downloading measurementset/alma/split11.ms/table.f6 (17 KB)
Checking out files: 97% (154/158)
Checking out files: 98% (155/158)
Downloading measurementset/alma/split11.ms/table.f9 (33 KB)
Checking out files: 99% (157/158)
Downloading measurementset/alma/split11.ms/table.lock (409 B)
Checking out files: 100% (158/158)
Checking out files: 100% (158/158), done.
Error response from daemon: No such container: wheel-container-test
Error response from daemon: No such container: wheel-container-test
2025-11-04 20:11:24 INFO GJonesSpline::solve() Accumulating data for: field= 1445+09900002_0, spw= 0, nchan= 63
2025-11-04 20:11:24 INFO GJonesSpline::solve() Accumulating data for: field= N5921_2, spw= 0, nchan= 63
2025-11-04 20:11:24 INFO GJonesSpline::solve() Number of timestamps in data = 60
2025-11-04 20:11:24 INFO GJonesSpline::getKnots() Gridded splinetime = 5280 sec.
2025-11-04 20:11:24 INFO GJonesSpline::solve() Number of cubic spline control points = 4
2025-11-04 20:11:24 INFO GJonesSpline::solve() Number of cubic spline knots = 8
2025-11-04 20:11:24 INFO GJonesSpline::solve() Number of cubic spline segments = 1
2025-11-04 20:11:24 INFO GJonesSpline::solve() Fitting amplitude spline.
2025-11-04 20:11:24 INFO GJonesSpline::solve() Searching for and correcting phase-wraps on each baseline.
2025-11-04 20:11:24 INFO GJonesSpline::solve() Fitting phase spline.
2025-11-04 20:11:24 INFO GJonesSpline::updateCalTable Storing solutions in table gainspline
2025-11-04 20:11:25 INFO calibrater::solve Finished solving.
2025-11-04 20:11:25 WARN calibrater::setvi(bool,bool) Forcing use of NEW VisibilityIterator.
2025-11-04 20:11:25 INFO calibrater::open ****Using NEW VI2-driven calibrater tool****
2025-11-04 20:11:25 INFO calibrater::open Opening MS: gaincaltest2.ms for calibration.
2025-11-04 20:11:25 INFO Clearing all model records in MS header.
2025-11-04 20:11:26 INFO VisSetUtil::addScrCols Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-11-04 20:11:27 INFO VisSetUtil::initScrCols Initializing MODEL_DATA to (unity).
2025-11-04 20:11:27 INFO VisSetUtil::initScrCols Initialized 328860 rows.
2025-11-04 20:11:27 INFO Calibrater:: Initializing nominal selection to the whole MS.
2025-11-04 20:11:27 INFO calibrater::specifycal Beginning specifycal-----------------------
2025-11-04 20:11:27 INFO Creating G Jones table from specified parameters.
2025-11-04 20:11:27 INFO Generating 'G' corrections.
2025-11-04 20:11:27 INFO Specified parameter(s) (per spw and pol) repeated on all antennas.
2025-11-04 20:11:27 INFO Specified parameter(s) (per spw and antenna) repeated on all polarizations.
2025-11-04 20:11:27 INFO spwId=1 antId=<all> polId=<all> parameter= 3 (ip0,ip1 = [0, 0, 0],[1, 0, 9])
2025-11-04 20:11:27 INFO Writing solutions to table: testcalout.cal
2025-11-04 20:11:29 WARN calibrater::setvi(bool,bool) Already using NEW VisibilityIterator.
2025-11-04 20:11:29 WARN calibrater::setvi(bool,bool) Forcing use of OLD VisibilityIterator.
2025-11-04 20:11:29 INFO calibrater::open ****Using OLD VI-driven calibrater tool****
2025-11-04 20:11:29 INFO calibrater::open Opening MS: gaincaltest2.ms for calibration.
2025-11-04 20:11:29 INFO Clearing all model records in MS header.
2025-11-04 20:11:29 INFO VisSetUtil::addScrCols Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-11-04 20:11:30 INFO VisSetUtil::initScrCols Initializing MODEL_DATA to (unity).
2025-11-04 20:11:31 INFO VisSetUtil::initScrCols Initialized 328860 rows.
2025-11-04 20:11:31 INFO Calibrater:: Initializing nominal selection to the whole MS.
2025-11-04 20:11:31 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
2025-11-04 20:11:31 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
2025-11-04 20:11:31 INFO Calibrater::setapply(type, applypar) . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:31 INFO calibrater::setsolve Beginning setsolve--(MSSelection version)-------
2025-11-04 20:11:31 INFO Calibrater::setsolve Arranging to SOLVE:
2025-11-04 20:11:31 INFO Calibrater::setsolve . Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-11-04 20:11:31 INFO Calibrater::state The following calibration terms are arranged for apply:
2025-11-04 20:11:31 INFO Calibrater::state . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:31 INFO Calibrater::state The following calibration term is arranged for solve:
2025-11-04 20:11:31 INFO Calibrater::state . Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-11-04 20:11:31 INFO calibrater::solve Beginning solve-----------------------------
2025-11-04 20:11:31 INFO Calibrater::solve The following calibration terms are arranged for apply:
2025-11-04 20:11:31 INFO Calibrater::solve . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:31 INFO Calibrater::solve The following calibration term is arranged for solve:
2025-11-04 20:11:31 INFO Calibrater::solve . Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-11-04 20:11:31 INFO Solving for Mf Mueller
2025-11-04 20:11:31 INFO For solint = inf, found 84 solution intervals.
2025-11-04 20:11:33 INFO Found good Mf Mueller solutions in 84 intervals.
2025-11-04 20:11:33 INFO Writing solutions to table: output.ms
2025-11-04 20:11:37 INFO calibrater::solve Finished solving.
2025-11-04 20:11:37 WARN calibrater::setvi(bool,bool) Forcing use of NEW VisibilityIterator.
2025-11-04 20:11:37 INFO calibrater::open ****Using NEW VI2-driven calibrater tool****
2025-11-04 20:11:37 INFO calibrater::open Opening MS: gaincaltest2.ms for calibration.
2025-11-04 20:11:37 INFO Clearing all model records in MS header.
2025-11-04 20:11:38 INFO VisSetUtil::addScrCols Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-11-04 20:11:40 INFO VisSetUtil::initScrCols Initializing MODEL_DATA to (unity).
2025-11-04 20:11:40 INFO VisSetUtil::initScrCols Initialized 328860 rows.
2025-11-04 20:11:40 INFO Calibrater:: Initializing nominal selection to the whole MS.
2025-11-04 20:11:40 INFO name:: No table opened.
2025-11-04 20:11:40 INFO showcache:: The Table Cache has the following 16 entries:
2025-11-04 20:11:40 INFO showcache:: 0: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
2025-11-04 20:11:40 INFO showcache:: 1: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
2025-11-04 20:11:40 INFO showcache:: 2: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
2025-11-04 20:11:40 INFO showcache:: 3: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
2025-11-04 20:11:40 INFO showcache:: 4: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
2025-11-04 20:11:40 INFO showcache:: 5: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
2025-11-04 20:11:40 INFO showcache:: 6: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
2025-11-04 20:11:40 INFO showcache:: 7: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
2025-11-04 20:11:40 INFO showcache:: 8: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
2025-11-04 20:11:40 INFO showcache:: 9: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
2025-11-04 20:11:40 INFO showcache:: 10: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
2025-11-04 20:11:40 INFO showcache:: 11: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
2025-11-04 20:11:40 INFO showcache:: 12: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
2025-11-04 20:11:40 INFO showcache:: 13: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
2025-11-04 20:11:40 INFO showcache:: 14: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
2025-11-04 20:11:40 INFO showcache:: 15: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
2025-11-04 20:11:41 INFO name:: No table opened.
2025-11-04 20:11:41 INFO showcache:: The Table Cache is empty.
2025-11-04 20:11:41 WARN calibrater::setvi(bool,bool) Already using NEW VisibilityIterator.
2025-11-04 20:11:41 INFO calibrater::open ****Using NEW VI2-driven calibrater tool****
2025-11-04 20:11:41 INFO calibrater::open Opening MS: gaincaltest2.ms for calibration.
2025-11-04 20:11:41 INFO Clearing all model records in MS header.
2025-11-04 20:11:41 INFO VisSetUtil::addScrCols Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-11-04 20:11:42 INFO VisSetUtil::initScrCols Initializing MODEL_DATA to (unity).
2025-11-04 20:11:43 INFO VisSetUtil::initScrCols Initialized 328860 rows.
2025-11-04 20:11:43 INFO Calibrater:: Initializing nominal selection to the whole MS.
2025-11-04 20:11:43 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
2025-11-04 20:11:43 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
2025-11-04 20:11:43 INFO Calibrater::setapply(type, applypar) . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:43 INFO calibrater::correct Beginning correct---------------------------
2025-11-04 20:11:43 INFO Calibrater::correct2 (VI2/VB2) The following calibration terms are arranged for apply:
2025-11-04 20:11:43 INFO Calibrater::correct2 (VI2/VB2) . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:45 INFO calibrater::correct Finished correcting.
2025-11-04 20:11:45 WARN calibrater::setvi(bool,bool) Already using NEW VisibilityIterator.
tar: work: Cannot stat: No such file or directory
tar: Exiting with failure status due to previous errors
Checking out files: 49% (78/158)
Downloading measurementset/alma/split11.ms/HISTORY/table.f0 (17 KB)
Checking out files: 50% (79/158)
Downloading measurementset/alma/split11.ms/HISTORY/table.lock (325 B)
Checking out files: 51% (81/158)
Downloading measurementset/alma/split11.ms/OBSERVATION/table.f0 (9.7 KB)
Checking out files: 52% (83/158)
Checking out files: 53% (84/158)
Checking out files: 54% (86/158)
Checking out files: 55% (87/158)
Downloading measurementset/alma/split11.ms/POINTING/table.f1 (33 KB)
Checking out files: 56% (89/158)
Checking out files: 57% (91/158)
Checking out files: 58% (92/158)
Checking out files: 59% (94/158)
Checking out files: 60% (95/158)
Checking out files: 61% (97/158)
Checking out files: 62% (98/158)
Checking out files: 62% (99/158)
Checking out files: 63% (100/158)
Downloading measurementset/alma/split11.ms/SOURCE/table.f0 (20 KB)
Checking out files: 64% (102/158)
Downloading measurementset/alma/split11.ms/SOURCE/table.f0i (80 B)
Checking out files: 65% (103/158)
Checking out files: 66% (105/158)
Checking out files: 67% (106/158)
Downloading measurementset/alma/split11.ms/SPECTRAL_WINDOW/table.f0 (27 KB)
Downloading measurementset/alma/split11.ms/SPECTRAL_WINDOW/table.f0i (33 KB)
Checking out files: 68% (108/158)
Checking out files: 68% (109/158)
Checking out files: 69% (110/158)
Checking out files: 70% (111/158)
Downloading measurementset/alma/split11.ms/STATE/table.f0 (3.6 KB)
Checking out files: 71% (113/158)
Downloading measurementset/alma/split11.ms/STATE/table.lock (325 B)
Checking out files: 72% (114/158)
Downloading measurementset/alma/split11.ms/SYSCAL/table.dat (5.2 KB)
Checking out files: 72% (115/158)
Downloading measurementset/alma/split11.ms/SYSCAL/table.f0 (16 KB)
Checking out files: 73% (116/158)
Downloading measurementset/alma/split11.ms/SYSCAL/table.f0i (1.3 MB)
Checking out files: 74% (117/158)
Checking out files: 75% (119/158)
Checking out files: 76% (121/158)
Checking out files: 77% (122/158)
Checking out files: 78% (124/158)
Downloading measurementset/alma/split11.ms/WEATHER/table.dat (5.3 KB)
Checking out files: 79% (125/158)
Downloading measurementset/alma/split11.ms/WEATHER/table.f0 (16 KB)
Checking out files: 80% (127/158)
Downloading measurementset/alma/split11.ms/WEATHER/table.lock (325 B)
Checking out files: 81% (128/158)
Downloading measurementset/alma/split11.ms/table.dat (11 KB)
Downloading measurementset/alma/split11.ms/table.f1 (33 KB)
Checking out files: 82% (130/158)
Downloading measurementset/alma/split11.ms/table.f10 (33 KB)
Downloading measurementset/alma/split11.ms/table.f11 (33 KB)
Checking out files: 83% (132/158)
Downloading measurementset/alma/split11.ms/table.f12 (33 KB)
Checking out files: 84% (133/158)
Checking out files: 84% (134/158)
Downloading measurementset/alma/split11.ms/table.f14 (66 KB)
Checking out files: 85% (135/158)
Downloading measurementset/alma/split11.ms/table.f15 (66 KB)
Checking out files: 86% (136/158)
Downloading measurementset/alma/split11.ms/table.f16 (66 KB)
Downloading measurementset/alma/split11.ms/table.f17 (483 B)
Checking out files: 87% (138/158)
Downloading measurementset/alma/split11.ms/table.f17_TSM1 (2.1 MB)
Checking out files: 87% (139/158)
Downloading measurementset/alma/split11.ms/table.f18 (331 B)
Checking out files: 88% (140/158)
Downloading measurementset/alma/split11.ms/table.f19 (276 B)
Checking out files: 89% (141/158)
Downloading measurementset/alma/split11.ms/table.f19_TSM0 (1.0 MB)
Downloading measurementset/alma/split11.ms/table.f2 (33 KB)
Checking out files: 90% (143/158)
Downloading measurementset/alma/split11.ms/table.f20 (483 B)
Checking out files: 91% (144/158)
Downloading measurementset/alma/split11.ms/table.f20_TSM1 (131 KB)
Checking out files: 91% (145/158)
Downloading measurementset/alma/split11.ms/table.f21 (470 B)
Checking out files: 92% (146/158)
Downloading measurementset/alma/split11.ms/table.f21_TSM1 (524 KB)
Checking out files: 93% (147/158)
Downloading measurementset/alma/split11.ms/table.f22 (472 B)
Downloading measurementset/alma/split11.ms/table.f22_TSM1 (524 KB)
Checking out files: 94% (149/158)
Checking out files: 95% (151/158)
Downloading measurementset/alma/split11.ms/table.f5 (33 KB)
Checking out files: 96% (152/158)
Downloading measurementset/alma/split11.ms/table.f6 (17 KB)
Checking out files: 97% (154/158)
Checking out files: 98% (155/158)
Downloading measurementset/alma/split11.ms/table.f9 (33 KB)
Checking out files: 99% (157/158)
Downloading measurementset/alma/split11.ms/table.lock (409 B)
Checking out files: 100% (158/158)
Checking out files: 100% (158/158), done.
Error response from daemon: No such container: wheel-container-test
Error response from daemon: No such container: wheel-container-test
2025-11-04 20:11:24 INFO GJonesSpline::solve() Accumulating data for: field= 1445+09900002_0, spw= 0, nchan= 63
2025-11-04 20:11:24 INFO GJonesSpline::solve() Accumulating data for: field= N5921_2, spw= 0, nchan= 63
2025-11-04 20:11:24 INFO GJonesSpline::solve() Number of timestamps in data = 60
2025-11-04 20:11:24 INFO GJonesSpline::getKnots() Gridded splinetime = 5280 sec.
2025-11-04 20:11:24 INFO GJonesSpline::solve() Number of cubic spline control points = 4
2025-11-04 20:11:24 INFO GJonesSpline::solve() Number of cubic spline knots = 8
2025-11-04 20:11:24 INFO GJonesSpline::solve() Number of cubic spline segments = 1
2025-11-04 20:11:24 INFO GJonesSpline::solve() Fitting amplitude spline.
2025-11-04 20:11:24 INFO GJonesSpline::solve() Searching for and correcting phase-wraps on each baseline.
2025-11-04 20:11:24 INFO GJonesSpline::solve() Fitting phase spline.
2025-11-04 20:11:24 INFO GJonesSpline::updateCalTable Storing solutions in table gainspline
2025-11-04 20:11:25 INFO calibrater::solve Finished solving.
2025-11-04 20:11:25 WARN calibrater::setvi(bool,bool) Forcing use of NEW VisibilityIterator.
2025-11-04 20:11:25 INFO calibrater::open ****Using NEW VI2-driven calibrater tool****
2025-11-04 20:11:25 INFO calibrater::open Opening MS: gaincaltest2.ms for calibration.
2025-11-04 20:11:25 INFO Clearing all model records in MS header.
2025-11-04 20:11:26 INFO VisSetUtil::addScrCols Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-11-04 20:11:27 INFO VisSetUtil::initScrCols Initializing MODEL_DATA to (unity).
2025-11-04 20:11:27 INFO VisSetUtil::initScrCols Initialized 328860 rows.
2025-11-04 20:11:27 INFO Calibrater:: Initializing nominal selection to the whole MS.
2025-11-04 20:11:27 INFO calibrater::specifycal Beginning specifycal-----------------------
2025-11-04 20:11:27 INFO Creating G Jones table from specified parameters.
2025-11-04 20:11:27 INFO Generating 'G' corrections.
2025-11-04 20:11:27 INFO Specified parameter(s) (per spw and pol) repeated on all antennas.
2025-11-04 20:11:27 INFO Specified parameter(s) (per spw and antenna) repeated on all polarizations.
2025-11-04 20:11:27 INFO spwId=1 antId=<all> polId=<all> parameter= 3 (ip0,ip1 = [0, 0, 0],[1, 0, 9])
2025-11-04 20:11:27 INFO Writing solutions to table: testcalout.cal
2025-11-04 20:11:29 WARN calibrater::setvi(bool,bool) Already using NEW VisibilityIterator.
2025-11-04 20:11:29 WARN calibrater::setvi(bool,bool) Forcing use of OLD VisibilityIterator.
2025-11-04 20:11:29 INFO calibrater::open ****Using OLD VI-driven calibrater tool****
2025-11-04 20:11:29 INFO calibrater::open Opening MS: gaincaltest2.ms for calibration.
2025-11-04 20:11:29 INFO Clearing all model records in MS header.
2025-11-04 20:11:29 INFO VisSetUtil::addScrCols Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-11-04 20:11:30 INFO VisSetUtil::initScrCols Initializing MODEL_DATA to (unity).
2025-11-04 20:11:31 INFO VisSetUtil::initScrCols Initialized 328860 rows.
2025-11-04 20:11:31 INFO Calibrater:: Initializing nominal selection to the whole MS.
2025-11-04 20:11:31 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
2025-11-04 20:11:31 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
2025-11-04 20:11:31 INFO Calibrater::setapply(type, applypar) . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:31 INFO calibrater::setsolve Beginning setsolve--(MSSelection version)-------
2025-11-04 20:11:31 INFO Calibrater::setsolve Arranging to SOLVE:
2025-11-04 20:11:31 INFO Calibrater::setsolve . Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-11-04 20:11:31 INFO Calibrater::state The following calibration terms are arranged for apply:
2025-11-04 20:11:31 INFO Calibrater::state . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:31 INFO Calibrater::state The following calibration term is arranged for solve:
2025-11-04 20:11:31 INFO Calibrater::state . Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-11-04 20:11:31 INFO calibrater::solve Beginning solve-----------------------------
2025-11-04 20:11:31 INFO Calibrater::solve The following calibration terms are arranged for apply:
2025-11-04 20:11:31 INFO Calibrater::solve . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:31 INFO Calibrater::solve The following calibration term is arranged for solve:
2025-11-04 20:11:31 INFO Calibrater::solve . Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
2025-11-04 20:11:31 INFO Solving for Mf Mueller
2025-11-04 20:11:31 INFO For solint = inf, found 84 solution intervals.
2025-11-04 20:11:33 INFO Found good Mf Mueller solutions in 84 intervals.
2025-11-04 20:11:33 INFO Writing solutions to table: output.ms
2025-11-04 20:11:37 INFO calibrater::solve Finished solving.
2025-11-04 20:11:37 WARN calibrater::setvi(bool,bool) Forcing use of NEW VisibilityIterator.
2025-11-04 20:11:37 INFO calibrater::open ****Using NEW VI2-driven calibrater tool****
2025-11-04 20:11:37 INFO calibrater::open Opening MS: gaincaltest2.ms for calibration.
2025-11-04 20:11:37 INFO Clearing all model records in MS header.
2025-11-04 20:11:38 INFO VisSetUtil::addScrCols Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-11-04 20:11:40 INFO VisSetUtil::initScrCols Initializing MODEL_DATA to (unity).
2025-11-04 20:11:40 INFO VisSetUtil::initScrCols Initialized 328860 rows.
2025-11-04 20:11:40 INFO Calibrater:: Initializing nominal selection to the whole MS.
2025-11-04 20:11:40 INFO name:: No table opened.
2025-11-04 20:11:40 INFO showcache:: The Table Cache has the following 16 entries:
2025-11-04 20:11:40 INFO showcache:: 0: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
2025-11-04 20:11:40 INFO showcache:: 1: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
2025-11-04 20:11:40 INFO showcache:: 2: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
2025-11-04 20:11:40 INFO showcache:: 3: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
2025-11-04 20:11:40 INFO showcache:: 4: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
2025-11-04 20:11:40 INFO showcache:: 5: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
2025-11-04 20:11:40 INFO showcache:: 6: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
2025-11-04 20:11:40 INFO showcache:: 7: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
2025-11-04 20:11:40 INFO showcache:: 8: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
2025-11-04 20:11:40 INFO showcache:: 9: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
2025-11-04 20:11:40 INFO showcache:: 10: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
2025-11-04 20:11:40 INFO showcache:: 11: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
2025-11-04 20:11:40 INFO showcache:: 12: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
2025-11-04 20:11:40 INFO showcache:: 13: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
2025-11-04 20:11:40 INFO showcache:: 14: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
2025-11-04 20:11:40 INFO showcache:: 15: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
2025-11-04 20:11:41 INFO name:: No table opened.
2025-11-04 20:11:41 INFO showcache:: The Table Cache is empty.
2025-11-04 20:11:41 WARN calibrater::setvi(bool,bool) Already using NEW VisibilityIterator.
2025-11-04 20:11:41 INFO calibrater::open ****Using NEW VI2-driven calibrater tool****
2025-11-04 20:11:41 INFO calibrater::open Opening MS: gaincaltest2.ms for calibration.
2025-11-04 20:11:41 INFO Clearing all model records in MS header.
2025-11-04 20:11:41 INFO VisSetUtil::addScrCols Adding MODEL_DATA and CORRECTED_DATA column(s).
2025-11-04 20:11:42 INFO VisSetUtil::initScrCols Initializing MODEL_DATA to (unity).
2025-11-04 20:11:43 INFO VisSetUtil::initScrCols Initialized 328860 rows.
2025-11-04 20:11:43 INFO Calibrater:: Initializing nominal selection to the whole MS.
2025-11-04 20:11:43 INFO calibrater::setapply Beginning setapply--(MSSelection version)-------
2025-11-04 20:11:43 INFO Calibrater::setapply(type, applypar) Arranging to APPLY:
2025-11-04 20:11:43 INFO Calibrater::setapply(type, applypar) . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:43 INFO calibrater::correct Beginning correct---------------------------
2025-11-04 20:11:43 INFO Calibrater::correct2 (VI2/VB2) The following calibration terms are arranged for apply:
2025-11-04 20:11:43 INFO Calibrater::correct2 (VI2/VB2) . G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
2025-11-04 20:11:45 INFO calibrater::correct Finished correcting.
2025-11-04 20:11:45 WARN calibrater::setvi(bool,bool) Already using NEW VisibilityIterator.
tar: work: Cannot stat: No such file or directory
tar: Exiting with failure status due to previous errors