Build: #3 was successful

Job: Test Tools Many Linux 2.28 was successful

Stages & jobs

  1. Default Stage

  2. Test

Build log

The build generated 51,042 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-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        calibrater::reset        Reseting solve/apply state
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        Calibrater::selectvis+         Selecting on scan: '2'
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 15660
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        calibrater::correct        Beginning correct---------------------------
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:30:31 2025-11-13 16:30:32        INFO        calibrater::correct        Finished correcting.
13-Nov-2025 11:30:32 2025-11-13 16:30:33        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:30:32 test_tool_calibrater.py::calibrater_test::test_selectVisScan PASSED
13-Nov-2025 11:30:33 2025-11-13 16:30:34        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
13-Nov-2025 11:30:33 2025-11-13 16:30:34        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
13-Nov-2025 11:30:33 2025-11-13 16:30:34        INFO                Clearing all model records in MS header.
13-Nov-2025 11:30:33 2025-11-13 16:30:34        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        calibrater::reset        Reseting solve/apply state
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::selectvis+         Selecting on spw: '0'
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 82215
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::selectvis        Frequency selection:
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::selectvis        .  Spw 0:0~7 (8 channels, step by 1)
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        calibrater::correct        Beginning correct---------------------------
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
13-Nov-2025 11:30:35 2025-11-13 16:30:35        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:30:36 2025-11-13 16:30:36        INFO        calibrater::correct        Finished correcting.
13-Nov-2025 11:30:37 2025-11-13 16:30:37        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:30:37 test_tool_calibrater.py::calibrater_test::test_selectVisSpw PASSED
13-Nov-2025 11:30:38 2025-11-13 16:30:38        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
13-Nov-2025 11:30:38 2025-11-13 16:30:38        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
13-Nov-2025 11:30:38 2025-11-13 16:30:38        INFO                Clearing all model records in MS header.
13-Nov-2025 11:30:38 2025-11-13 16:30:38        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:30:39 2025-11-13 16:30:39        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        calibrater::reset        Reseting solve/apply state
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        Calibrater::selectvis+         Selecting on time: '>04:38:23'
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 313200
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        calibrater::correct        Beginning correct---------------------------
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
13-Nov-2025 11:30:39 2025-11-13 16:30:40        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:30:43 2025-11-13 16:30:43        INFO        calibrater::correct        Finished correcting.
13-Nov-2025 11:30:43 2025-11-13 16:30:44        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:30:44 test_tool_calibrater.py::calibrater_test::test_selectVisTime PASSED
13-Nov-2025 11:30:44 2025-11-13 16:30:44        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
13-Nov-2025 11:30:44 2025-11-13 16:30:44        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
13-Nov-2025 11:30:44 2025-11-13 16:30:44        INFO                Clearing all model records in MS header.
13-Nov-2025 11:30:44 2025-11-13 16:30:44        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:30:45 2025-11-13 16:30:46        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:30:46 2025-11-13 16:30:46        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
13-Nov-2025 11:30:46 2025-11-13 16:30:46        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:30:46 2025-11-13 16:30:46        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
13-Nov-2025 11:30:46 2025-11-13 16:30:46        INFO        calibrater::reset        Reseting solve/apply state
13-Nov-2025 11:30:46 2025-11-13 16:30:46        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
13-Nov-2025 11:30:46 2025-11-13 16:30:46        INFO        Calibrater::selectvis+         Selecting on uvrange: '> 500000lambda'
13-Nov-2025 11:30:46 2025-11-13 16:30:47        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 103321
13-Nov-2025 11:30:46 2025-11-13 16:30:47        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
13-Nov-2025 11:30:46 2025-11-13 16:30:47        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
13-Nov-2025 11:30:46 2025-11-13 16:30:47        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
13-Nov-2025 11:30:46 2025-11-13 16:30:47        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
13-Nov-2025 11:30:46 2025-11-13 16:30:47        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:30:46 2025-11-13 16:30:47        INFO        calibrater::correct        Beginning correct---------------------------
13-Nov-2025 11:30:46 2025-11-13 16:30:47        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
13-Nov-2025 11:30:46 2025-11-13 16:30:47        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:30:48 2025-11-13 16:30:49        INFO        calibrater::correct        Finished correcting.
13-Nov-2025 11:30:49 2025-11-13 16:30:49        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:30:49 test_tool_calibrater.py::calibrater_test::test_selectVisUVrange PASSED
13-Nov-2025 11:30:49 2025-11-13 16:30:50        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
13-Nov-2025 11:30:49 2025-11-13 16:30:50        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
13-Nov-2025 11:30:49 2025-11-13 16:30:50        INFO                Clearing all model records in MS header.
13-Nov-2025 11:30:49 2025-11-13 16:30:50        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:30:51 2025-11-13 16:30:51        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        calibrater::parsecallibfile        Beginning parsecallibfile-)-------
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        calibrater::setcallib        Beginning setcallib---------
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        Calibrater::setcallib2(callib)        Arranging to APPLY:
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO                .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO                .   0:
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        +             MS: obs= scan= fld= intent= spw=
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        +             CT: tinterp=linear finterp=
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        +                 obsmap=[]         scanmap=[]         fldmap=[]
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        +                 spwmap=[]         antmap=[]
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        calibrater::corrupt        Beginning corrupt---------------------------
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
13-Nov-2025 11:30:51 2025-11-13 16:30:52        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
13-Nov-2025 11:30:54 2025-11-13 16:30:54        INFO        calibrater::corrupt        Finished corrupting.
13-Nov-2025 11:30:55 2025-11-13 16:30:55        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:30:55 test_tool_calibrater.py::calibrater_test::test_setCalLib PASSED
13-Nov-2025 11:30:55 2025-11-13 16:30:56        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
13-Nov-2025 11:30:55 2025-11-13 16:30:56        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
13-Nov-2025 11:30:55 2025-11-13 16:30:56        INFO                Clearing all model records in MS header.
13-Nov-2025 11:30:55 2025-11-13 16:30:56        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:30:56 2025-11-13 16:30:57        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:30:57 2025-11-13 16:30:57        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
13-Nov-2025 11:30:57 2025-11-13 16:30:57        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:30:57 2025-11-13 16:30:57        INFO        Calibrater::setCorrDepFlags        Setting correlation dependent flags = True
13-Nov-2025 11:30:57 2025-11-13 16:30:57        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:30:57 test_tool_calibrater.py::calibrater_test::test_setCorrDepFlags PASSED
13-Nov-2025 11:30:57 2025-11-13 16:30:58        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
13-Nov-2025 11:30:57 2025-11-13 16:30:58        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
13-Nov-2025 11:30:57 2025-11-13 16:30:58        INFO                Clearing all model records in MS header.
13-Nov-2025 11:30:57 2025-11-13 16:30:58        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:30:57 2025-11-13 16:30:58        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:30:57 2025-11-13 16:30:58        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
13-Nov-2025 11:30:57 2025-11-13 16:30:58        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:30:57 2025-11-13 16:30:58        INFO        calibrater::smooth        Beginning smooth--(MSSelection version)-------
13-Nov-2025 11:30:57 2025-11-13 16:30:58        INFO        Calibrater::smooth        Beginning smoothing/interpolating method.
13-Nov-2025 11:30:57 2025-11-13 16:30:58        WARN                Found pre-v4.1 caltable (/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/ngc5921.gcal); attempting to update...
13-Nov-2025 11:30:57 2025-11-13 16:30:58        WARN        +        SUCCEEDED: trivial OBSERVATION/OBSERVATION_ID have been added.
13-Nov-2025 11:30:58 2025-11-13 16:30:58        INFO        Calibrater::smooth        Storing result in testcalout.cal
13-Nov-2025 11:30:58 2025-11-13 16:30:58        INFO                Writing solutions to table: testcalout.cal
13-Nov-2025 11:30:58 2025-11-13 16:30:58        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:30:58 test_tool_calibrater.py::calibrater_test::test_smoothedCalTables PASSED
13-Nov-2025 11:30:58 2025-11-13 16:30:58        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
13-Nov-2025 11:30:58 2025-11-13 16:30:58        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
13-Nov-2025 11:30:58 2025-11-13 16:30:58        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
13-Nov-2025 11:30:58 2025-11-13 16:30:58        INFO                Clearing all model records in MS header.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        calibrater::setsolvebandpoly        Beginning setsolvebandpoly--(MSSelection version)-------
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        Calibrater::setsolvebandpoly        Arranging to SOLVE:
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        Calibrater::setsolvebandpoly        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        calibrater::solve        Beginning solve-----------------------------
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        Calibrater::solve        .   (None)
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        Calibrater::solve        The following calibration term is arranged for solve:
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        Calibrater::solve        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        Calibrater::solve        Solving for BPOLY
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO                For solint = inf, found 7 solution intervals.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.059507/0.0681762/0.0765806
13-Nov-2025 11:30:58 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.336529/0.750193/1.6779
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0630839/0.0689061/0.0756384
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.279482/0.74979/1.59355
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0672607/0.089081/0.111106
13-Nov-2025 11:30:59 2025-11-13 16:30:59        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:30:59 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.31075/3.32219/4.29305
13-Nov-2025 11:30:59 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:30:59 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0652822/0.0889198/0.116912
13-Nov-2025 11:30:59 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.44269/3.26616/4.3519
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350681/0.587724/0.894558
13-Nov-2025 11:31:00 2025-11-13 16:31:00        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:02 2025-11-13 16:31:03        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.296/59.0077/111.454
13-Nov-2025 11:31:02 2025-11-13 16:31:03        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:02 2025-11-13 16:31:03        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.354236/0.581733/0.988903
13-Nov-2025 11:31:02 2025-11-13 16:31:03        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 17.2419/56.3249/117.264
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0850169/0.113968/0.15276
13-Nov-2025 11:31:05 2025-11-13 16:31:05        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.67561/5.33243/8.17296
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0784236/0.114345/0.159908
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.46392/5.27232/7.20673
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0703936/0.102501/0.131676
13-Nov-2025 11:31:05 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:06 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.98728/4.47977/5.84264
13-Nov-2025 11:31:06 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:06 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0751114/0.102994/0.138529
13-Nov-2025 11:31:06 2025-11-13 16:31:06        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.18629/4.47068/5.8941
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350626/0.604424/0.974388
13-Nov-2025 11:31:06 2025-11-13 16:31:07        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:09 2025-11-13 16:31:09        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.2542/59.3511/120.719
13-Nov-2025 11:31:09 2025-11-13 16:31:09        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:09 2025-11-13 16:31:09        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.382806/0.598119/0.941571
13-Nov-2025 11:31:09 2025-11-13 16:31:09        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 20.0637/57.2112/118.101
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0750931/0.0978998/0.125897
13-Nov-2025 11:31:11 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:12 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.72932/3.98787/5.33715
13-Nov-2025 11:31:12 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
13-Nov-2025 11:31:12 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0658572/0.0972125/0.140794
13-Nov-2025 11:31:12 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
13-Nov-2025 11:31:12 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.97943/3.97841/5.72903
13-Nov-2025 11:31:12 2025-11-13 16:31:12        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
13-Nov-2025 11:31:12 2025-11-13 16:31:12        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
13-Nov-2025 11:31:12 2025-11-13 16:31:12        INFO        Calibrater::solve          Found good BPOLY solutions in 7 slots.
13-Nov-2025 11:31:12 2025-11-13 16:31:12        INFO        calibrater::solve        Finished solving.
13-Nov-2025 11:31:12 2025-11-13 16:31:12        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
13-Nov-2025 11:31:12 test_tool_calibrater.py::calibrater_test::test_solveBandpass PASSED
13-Nov-2025 11:31:12 2025-11-13 16:31:13        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO                Clearing all model records in MS header.
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        calibrater::setsolvegainspline        Beginning setsolvegainspline--(MSSelection version)-------
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        Calibrater::setsolvegainspline        Arranging to SOLVE:
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        Calibrater::setsolvegainspline        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        calibrater::solve        Beginning solve-----------------------------
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        Calibrater::solve        .   (None)
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        Calibrater::solve        The following calibration term is arranged for solve:
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        Calibrater::solve        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Fitting time-dependent cubic splines.
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Solving for phase splines with splinetime= 10800
13-Nov-2025 11:31:12 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Solving for amplitude splines with splinetime= 10800
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1331+30500002_0, spw= 0, nchan= 63
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1445+09900002_0, spw= 0, nchan= 63
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Accumulating data for:  field= N5921_2, spw= 0, nchan= 63
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Number of timestamps in data = 60
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::getKnots()         Gridded splinetime = 5280 sec.
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Number of cubic spline control points = 4
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Number of cubic spline knots = 8
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Number of cubic spline segments = 1
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Fitting amplitude spline.
13-Nov-2025 11:31:13 test_tool_calibrater.py::calibrater_test::test_solveGainspline  DPBTRF ;problem leading minor           23
13-Nov-2025 11:31:13 DPBTRF ;problem leading minor           23
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Searching for and correcting phase-wraps on each baseline.
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::solve()         Fitting phase spline.
13-Nov-2025 11:31:13 DPBTRF ;problem leading minor           22
13-Nov-2025 11:31:13 DPBTRF ;problem leading minor           22
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        GJonesSpline::updateCalTable         Storing solutions in table gainspline
13-Nov-2025 11:31:13 2025-11-13 16:31:13        INFO        calibrater::solve        Finished solving.
13-Nov-2025 11:31:13 2025-11-13 16:31:13        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
13-Nov-2025 11:31:13 PASSED
13-Nov-2025 11:31:13 2025-11-13 16:31:14        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
13-Nov-2025 11:31:13 2025-11-13 16:31:14        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
13-Nov-2025 11:31:13 2025-11-13 16:31:14        INFO                Clearing all model records in MS header.
13-Nov-2025 11:31:13 2025-11-13 16:31:14        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:31:15 2025-11-13 16:31:15        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:31:15 2025-11-13 16:31:16        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
13-Nov-2025 11:31:15 2025-11-13 16:31:16        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:31:15 2025-11-13 16:31:16        INFO        calibrater::specifycal        Beginning specifycal-----------------------
13-Nov-2025 11:31:15 2025-11-13 16:31:16        INFO                Creating G Jones table from specified parameters.
13-Nov-2025 11:31:15 2025-11-13 16:31:16        INFO                Generating 'G' corrections.
13-Nov-2025 11:31:15 2025-11-13 16:31:16        INFO                Specified parameter(s) (per spw and pol) repeated on all antennas.
13-Nov-2025 11:31:15 2025-11-13 16:31:16        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
13-Nov-2025 11:31:15 2025-11-13 16:31:16        INFO                spwId=1 antId=<all> polId=<all> parameter= 3   (ip0,ip1 = [0, 0, 0],[1, 0, 9])
13-Nov-2025 11:31:15 2025-11-13 16:31:16        INFO                Writing solutions to table: testcalout.cal
13-Nov-2025 11:31:15 2025-11-13 16:31:16        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:31:15 test_tool_calibrater.py::calibrater_test::test_specifyCal PASSED
13-Nov-2025 11:31:16 2025-11-13 16:31:16        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
13-Nov-2025 11:31:16 2025-11-13 16:31:16        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
13-Nov-2025 11:31:16 2025-11-13 16:31:16        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
13-Nov-2025 11:31:16 2025-11-13 16:31:16        INFO                Clearing all model records in MS header.
13-Nov-2025 11:31:16 2025-11-13 16:31:16        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:31:17 2025-11-13 16:31:17        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:31:17 2025-11-13 16:31:18        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
13-Nov-2025 11:31:17 2025-11-13 16:31:18        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:31:17 2025-11-13 16:31:18        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
13-Nov-2025 11:31:17 2025-11-13 16:31:18        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::setsolve        Arranging to SOLVE:
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::setsolve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::state        The following calibration terms are arranged for apply:
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::state        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::state        The following calibration term is arranged for solve:
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::state        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        calibrater::solve        Beginning solve-----------------------------
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::solve        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::solve        The following calibration term is arranged for solve:
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO        Calibrater::solve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO                Solving for Mf Mueller
13-Nov-2025 11:31:18 2025-11-13 16:31:18        INFO                For solint = inf, found 84 solution intervals.
13-Nov-2025 11:31:20 2025-11-13 16:31:20        INFO                  Found good Mf Mueller solutions in 84 intervals.
13-Nov-2025 11:31:20 2025-11-13 16:31:20        INFO                Writing solutions to table: output.ms
13-Nov-2025 11:31:20 2025-11-13 16:31:20        INFO        calibrater::solve        Finished solving.
13-Nov-2025 11:31:20 2025-11-13 16:31:20        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
13-Nov-2025 11:31:20 test_tool_calibrater.py::calibrater_test::test_standardPath PASSED
13-Nov-2025 11:31:20 2025-11-13 16:31:21        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
13-Nov-2025 11:31:20 2025-11-13 16:31:21        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
13-Nov-2025 11:31:20 2025-11-13 16:31:21        INFO                Clearing all model records in MS header.
13-Nov-2025 11:31:20 2025-11-13 16:31:21        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:31:21 2025-11-13 16:31:22        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        name::        No table opened.
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::        The Table Cache has the following 16 entries:
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            0: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            1: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            2: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            3: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            4: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            5: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            6: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            7: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            8: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            9: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            10: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            11: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            12: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            13: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            14: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::            15: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        name::        No table opened.
13-Nov-2025 11:31:22 2025-11-13 16:31:22        INFO        showcache::        The Table Cache is empty.
13-Nov-2025 11:31:22 2025-11-13 16:31:22        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:31:22 test_tool_calibrater.py::calibrater_test::test_takesMs PASSED
13-Nov-2025 11:31:22 2025-11-13 16:31:23        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
13-Nov-2025 11:31:22 2025-11-13 16:31:23        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
13-Nov-2025 11:31:22 2025-11-13 16:31:23        INFO                Clearing all model records in MS header.
13-Nov-2025 11:31:22 2025-11-13 16:31:23        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
13-Nov-2025 11:31:24 2025-11-13 16:31:24        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
13-Nov-2025 11:31:24 2025-11-13 16:31:24        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
13-Nov-2025 11:31:24 2025-11-13 16:31:24        INFO        Calibrater::        Initializing nominal selection to the whole MS.
13-Nov-2025 11:31:24 2025-11-13 16:31:24        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
13-Nov-2025 11:31:24 2025-11-13 16:31:24        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
13-Nov-2025 11:31:24 2025-11-13 16:31:24        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:31:24 2025-11-13 16:31:24        INFO        calibrater::correct        Beginning correct---------------------------
13-Nov-2025 11:31:24 2025-11-13 16:31:24        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
13-Nov-2025 11:31:24 2025-11-13 16:31:24        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
13-Nov-2025 11:31:27 2025-11-13 16:31:28        INFO        calibrater::correct        Finished correcting.
13-Nov-2025 11:31:27 2025-11-13 16:31:28        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
13-Nov-2025 11:31:27 test_tool_calibrater.py::calibrater_test::test_writeToCorrected PASSED
13-Nov-2025 11:31:27
13-Nov-2025 11:31:27 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml -
13-Nov-2025 11:31:27 ======================== 24 passed in 114.24s (0:01:54) ========================
13-Nov-2025 11:31:28 ============================= test session starts ==============================
13-Nov-2025 11:31:28 platform linux -- Python 3.12.9, pytest-9.0.1, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.12
13-Nov-2025 11:31:28 cachedir: .pytest_cache
13-Nov-2025 11:31:28 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata
13-Nov-2025 11:31:28 configfile: pytest.ini
13-Nov-2025 11:31:29 collecting ... collected 85 items
13-Nov-2025 11:31:29
13-Nov-2025 11:31:29 2025-11-13 16:31:29        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:29 2025-11-13 16:31:29        SEVERE        msmetadata_cmpt.cc::chanwidths        Exception Reported: From/to units not consistent. Cannot convert Hz to km/s at File: /source/casainstall/include/casacore/casa/Quanta/Quantum.tcc, line: 256
13-Nov-2025 11:31:29 test_tool_msmetadata.py::msmetadata_test::test_CAS7463 PASSED
13-Nov-2025 11:31:29 2025-11-13 16:31:29        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:29 2025-11-13 16:31:29        INFO        MSFitsInput::MSFitsInput        Converting FITS file '/opt/casa/data/casatestdata/unittest/msmetadata/W3OH_MC.UVFITS' to MeasurementSet 'lala.ms'
13-Nov-2025 11:31:29 2025-11-13 16:31:29        INFO        MSFitsInput::setupMeasurementSet        Using tile shape [1, 38, 3449] for VLA with obstype=0
13-Nov-2025 11:31:29 2025-11-13 16:31:30        INFO        MSFitsInput::fillMSMainTableColWise        SOURCE not found in UU---SIN VV---SIN WW---SIN BASELINE DATE DATE
13-Nov-2025 11:31:29 2025-11-13 16:31:30        INFO        MSFitsInput::fillMSMainTableColWise        FREQSEL not found in UU---SIN VV---SIN WW---SIN BASELINE DATE DATE
13-Nov-2025 11:31:29 2025-11-13 16:31:30        INFO        MSFitsInput::fillMSMainTableColWise        Reading and writing 16311 visibility groups
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::readRandomGroupUVFits        extname=AIPS NX  nrows=1 ncols=7 rowsize=28 pcount=0 gcount=1
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::readRandomGroupUVFits        Skipping table, duplicate or unrecognized type: AIPS NX
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::readRandomGroupUVFits        extname=AIPS FQ  nrows=1 ncols=5 rowsize=24 pcount=0 gcount=1
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::readRandomGroupUVFits        extname=AIPS WX  nrows=155 ncols=11 rowsize=48 pcount=0 gcount=1
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::readRandomGroupUVFits        Skipping table, duplicate or unrecognized type: AIPS WX
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::readRandomGroupUVFits        extname=AIPS OF  nrows=100 ncols=7 rowsize=28 pcount=0 gcount=1
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::readRandomGroupUVFits        Skipping table, duplicate or unrecognized type: AIPS OF
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::readRandomGroupUVFits        extname=AIPS AN  nrows=29 ncols=14 rowsize=78 pcount=0 gcount=1
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::fillAntennaTable        This looks like an old VLA archive UVFITS file
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::fillAntennaTable        UVFITS file telescope position is 2.13393e-09 meters from CASA Observatories table VLA position
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::fillAntennaTable        Performing transformation of antenna positions from coordinate frame used by MODCOMPs to ITRF
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        MSFitsInput::fillExtraTables        Filling SOURCE table (this may take some time).
13-Nov-2025 11:31:30 2025-11-13 16:31:30        WARN        MSFitsInput::fillExtraTable        No systemic velocity for field 0
13-Nov-2025 11:31:30 2025-11-13 16:31:30        WARN        MSFitsInput::fillExtraTable        No rest frequency for field 0
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on lala.ms...
13-Nov-2025 11:31:30 test_tool_msmetadata.py::msmetadata_test::test_CAS7837 PASSED
13-Nov-2025 11:31:30 2025-11-13 16:31:30        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:30 2025-11-13 16:31:31        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on cas7986.ms...
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        ms::close        Exception Reported: Exception: Illegal ANTENNA1 value 9 found in main table. /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/cas7986.ms/ANTENNA only has 8 rows (IDs).
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        ms::close+        ... thrown by void casa::MSChecker::checkReferentialIntegrity() const at File: /source/casa6/casatools/src/code/msvis/MSVis/MSChecker.cc, line: 73
13-Nov-2025 11:31:30 2025-11-13 16:31:31        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on cas7986.ms...
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        msmetadata_cmpt.cc::open        Exception Reported: Exception: Illegal ANTENNA1 value 9 found in main table. /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/cas7986.ms/ANTENNA only has 8 rows (IDs).
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        msmetadata_cmpt.cc::open+        ... thrown by void casa::MSChecker::checkReferentialIntegrity() const at File: /source/casa6/casatools/src/code/msvis/MSVis/MSChecker.cc, line: 73
13-Nov-2025 11:31:30 2025-11-13 16:31:31        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on cas7986.ms...
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        ms::close        Exception Reported: Exception: Illegal ANTENNA2 value 9 found in main table. /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/cas7986.ms/ANTENNA only has 8 rows (IDs).
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        ms::close+        ... thrown by void casa::MSChecker::checkReferentialIntegrity() const at File: /source/casa6/casatools/src/code/msvis/MSVis/MSChecker.cc, line: 73
13-Nov-2025 11:31:30 2025-11-13 16:31:31        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on cas7986.ms...
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        msmetadata_cmpt.cc::open        Exception Reported: Exception: Illegal ANTENNA2 value 9 found in main table. /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/cas7986.ms/ANTENNA only has 8 rows (IDs).
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        msmetadata_cmpt.cc::open+        ... thrown by void casa::MSChecker::checkReferentialIntegrity() const at File: /source/casa6/casatools/src/code/msvis/MSVis/MSChecker.cc, line: 73
13-Nov-2025 11:31:30 2025-11-13 16:31:31        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on cas7986.ms...
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        ms::close        Exception Reported: Exception: Illegal DATA_DESC_ID value 9 found in main table. /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/cas7986.ms/DATA_DESCRIPTION only has 3 rows (IDs).
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        ms::close+        ... thrown by void casa::MSChecker::checkReferentialIntegrity() const at File: /source/casa6/casatools/src/code/msvis/MSVis/MSChecker.cc, line: 73
13-Nov-2025 11:31:30 2025-11-13 16:31:31        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on cas7986.ms...
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        msmetadata_cmpt.cc::open        Exception Reported: Exception: Illegal DATA_DESC_ID value 9 found in main table. /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/cas7986.ms/DATA_DESCRIPTION only has 3 rows (IDs).
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        msmetadata_cmpt.cc::open+        ... thrown by void casa::MSChecker::checkReferentialIntegrity() const at File: /source/casa6/casatools/src/code/msvis/MSVis/MSChecker.cc, line: 73
13-Nov-2025 11:31:30 2025-11-13 16:31:31        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on cas7986.ms...
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        ms::close        Exception Reported: Exception: Illegal FIELD_ID value 9 found in main table. /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/cas7986.ms/FIELD only has 1 rows (IDs).
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        ms::close+        ... thrown by void casa::MSChecker::checkReferentialIntegrity() const at File: /source/casa6/casatools/src/code/msvis/MSVis/MSChecker.cc, line: 73
13-Nov-2025 11:31:30 2025-11-13 16:31:31        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on cas7986.ms...
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        msmetadata_cmpt.cc::open        Exception Reported: Exception: Illegal FIELD_ID value 9 found in main table. /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/cas7986.ms/FIELD only has 1 rows (IDs).
13-Nov-2025 11:31:30 2025-11-13 16:31:31        SEVERE        msmetadata_cmpt.cc::open+        ... thrown by void casa::MSChecker::checkReferentialIntegrity() const at File: /source/casa6/casatools/src/code/msvis/MSVis/MSChecker.cc, line: 73
13-Nov-2025 11:31:30 test_tool_msmetadata.py::msmetadata_test::test_CAS7986 PASSED
13-Nov-2025 11:31:30 2025-11-13 16:31:31        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:31 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/fdmtdm.ms...
13-Nov-2025 11:31:31 test_tool_msmetadata.py::msmetadata_test::test_alma_fdmtdm_heavy_online_averaging PASSED
13-Nov-2025 11:31:31 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:31 test_tool_msmetadata.py::msmetadata_test::test_almaspws PASSED
13-Nov-2025 11:31:31 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:31 test_tool_msmetadata.py::msmetadata_test::test_antennadiamter PASSED
13-Nov-2025 11:31:31 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:31 test_tool_msmetadata.py::msmetadata_test::test_antennanames_and_antennaids PASSED
13-Nov-2025 11:31:31 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:31 2025-11-13 16:31:32        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::antennasforscan        Exception Reported: Exception: No matching scans found.
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::antennasforscan+        ... thrown by std::set<casacore::ScanKey> casac::msmetadata::_getScanKeys(long int, long int, long int) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2485
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::antennasforscan        Exception Reported: Exception: No matching scans found.
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::antennasforscan+        ... thrown by std::set<casacore::ScanKey> casac::msmetadata::_getScanKeys(long int, long int, long int) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2485
13-Nov-2025 11:31:31 test_tool_msmetadata.py::msmetadata_test::test_antennasforscan PASSED
13-Nov-2025 11:31:31 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::antennastations        Exception Reported: No antenna ID may be less than zero when multiple IDs specified.
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::antennastations        Exception Reported: Exception: MSMetaData::_hasAntennaID: Requested antenna ID 20 is greater than or equal to the number of records (15) in this MS's ANTENNA table.
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::antennastations+        ... thrown by void casacore::MSMetaData::_hasAntennaID(casacore::Int) at File: /source/casa6/casatools/casacore/ms/MSOper/MSMetaData.cc, line: 4928
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::antennastations        Exception Reported: Exception: MSMetaData::_hasAntennaID: Requested antenna ID 20 is greater than or equal to the number of records (15) in this MS's ANTENNA table.
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::antennastations+        ... thrown by void casacore::MSMetaData::_hasAntennaID(casacore::Int) at File: /source/casa6/casatools/casacore/ms/MSOper/MSMetaData.cc, line: 4928
13-Nov-2025 11:31:31 test_tool_msmetadata.py::msmetadata_test::test_antennastations PASSED
13-Nov-2025 11:31:31 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::bandwidths        Exception Reported: Exception: Spectral window ID 50 out of range, must be less than or equal to 39.
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::bandwidths+        ... thrown by void casac::msmetadata::_checkSpwId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2443
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::bandwidths        Exception Reported: Exception: Spectral window ID 50 out of range, must be less than or equal to 39.
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::bandwidths+        ... thrown by void casac::msmetadata::_checkSpwId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2443
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::bandwidths        Exception Reported: Exception: When specified as an array, no element of spws may be < 0.
13-Nov-2025 11:31:31 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::bandwidths+        ... thrown by casac::variant* casac::msmetadata::bandwidths(const casac::variant&) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 575
13-Nov-2025 11:31:31 test_tool_msmetadata.py::msmetadata_test::test_bandwidths PASSED
13-Nov-2025 11:31:31 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:31 test_tool_msmetadata.py::msmetadata_test::test_chanavgspws PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_chaneffbws PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_chanres PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::chanwidths        Exception Reported: Exception: Spectral window ID 50 out of range, must be nonnegative and less than or equal to 39.
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::chanwidths+        ... thrown by void casac::msmetadata::_checkSpwId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2443
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::chanwidths        Exception Reported: Exception: Spectral window ID -2 out of range, must be nonnegative and less than or equal to 39.
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::chanwidths+        ... thrown by void casac::msmetadata::_checkSpwId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2443
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::chanwidths        Exception Reported: Tool is not attached to an MS. Use open()
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::chanfreqs        Exception Reported: Tool is not attached to an MS. Use open()
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::meanfreq        Exception Reported: Tool is not attached to an MS. Use open()
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::sideband        Exception Reported: Tool is not attached to an MS. Use open()
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::effexposuretime        Exception Reported: Tool is not attached to an MS. Use open()
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_chanwidths PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_corrbit PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::corrprodsforpol        Exception Reported: Exception: Polarization ID cannot be negative.
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::corrprodsforpol+        ... thrown by void casac::msmetadata::_checkPolId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2459
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::corrprodsforpol        Exception Reported: Exception: Polarization ID 2 out of range, must be less than 2.
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::corrprodsforpol+        ... thrown by void casac::msmetadata::_checkPolId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2453
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_corrprodsforpol PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::corrtypesforpol        Exception Reported: Exception: Polarization ID cannot be negative.
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::corrtypesforpol+        ... thrown by void casac::msmetadata::_checkPolId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2459
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::corrtypesforpol        Exception Reported: Exception: Polarization ID 2 out of range, must be less than 2.
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::corrtypesforpol+        ... thrown by void casac::msmetadata::_checkPolId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2453
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_corrtypesforpol PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_datadescids PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::exposuretime        Exception Reported: Exception: MS has no data description ID for spectral window ID 0 and polarization ID 0.
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::exposuretime+        ... thrown by casac::record* casac::msmetadata::exposuretime(long int, long int, long int, long int, long int) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 816
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_exposuretime PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_fdmspws PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_fieldnames PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_fieldsforintent PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_fieldsforname PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::fieldsforscans        Exception Reported: Exception: When asmap is true, both obsid and arrayid must be nonnegative.
13-Nov-2025 11:31:32 2025-11-13 16:31:32        SEVERE        msmetadata_cmpt.cc::fieldsforscans+        ... thrown by casac::variant* casac::msmetadata::fieldsforscans(const std::vector<long int>&, bool, long int, long int, bool) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 944
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_fieldsforscan PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_fieldsforsource PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_fieldsforspw PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_fieldsfortimes PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_intents PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_intentsforfield PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_intentsforscan PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:32        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_intentsforspw PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_name PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_namesforfields PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::namesforspws        Exception Reported: Exception: Spectral window ID must be nonnegative..
13-Nov-2025 11:31:32 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::namesforspws+        ... thrown by std::vector<std::__cxx11::basic_string<char> > casac::msmetadata::namesforspws(const casac::variant&) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 1171
13-Nov-2025 11:31:32 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::namesforspws        Exception Reported: Exception: All spectral window IDs must be nonnegative..
13-Nov-2025 11:31:32 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::namesforspws+        ... thrown by std::vector<std::__cxx11::basic_string<char> > casac::msmetadata::namesforspws(const casac::variant&) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 1181
13-Nov-2025 11:31:32 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::namesforspws        Exception Reported: Exception: Spectral window ID must be less than total number of spws.
13-Nov-2025 11:31:32 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::namesforspws+        ... thrown by std::vector<std::__cxx11::basic_string<char> > casac::msmetadata::namesforspws(const casac::variant&) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 1172
13-Nov-2025 11:31:32 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::namesforspws        Exception Reported: Exception: All spectral window IDs must be less than the total number of spws.
13-Nov-2025 11:31:32 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::namesforspws+        ... thrown by std::vector<std::__cxx11::basic_string<char> > casac::msmetadata::namesforspws(const casac::variant&) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 1185
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_namesforspws PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_nantennas PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_narrays PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_nbaselines PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_ncorrforpol PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_nfields PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_nobservations PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_nscans PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_nsources PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_nspw PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_nstates PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_nvis PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:32 test_tool_msmetadata.py::msmetadata_test::test_observers PASSED
13-Nov-2025 11:31:32 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:33 test_tool_msmetadata.py::msmetadata_test::test_pointingdirection PASSED
13-Nov-2025 11:31:33 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:33 test_tool_msmetadata.py::msmetadata_test::test_polidfordatadesc PASSED
13-Nov-2025 11:31:33 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:33 test_tool_msmetadata.py::msmetadata_test::test_projects PASSED
13-Nov-2025 11:31:33 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:33 test_tool_msmetadata.py::msmetadata_test::test_propermotions PASSED
13-Nov-2025 11:31:33 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:33 test_tool_msmetadata.py::msmetadata_test::test_refdir PASSED
13-Nov-2025 11:31:33 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:33 test_tool_msmetadata.py::msmetadata_test::test_reffreq PASSED
13-Nov-2025 11:31:33 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:33 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::restfreqs        Exception Reported: Exception: sourceid cannot be negative.
13-Nov-2025 11:31:33 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::restfreqs+        ... thrown by casac::variant* casac::msmetadata::restfreqs(long int, long int) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 1519
13-Nov-2025 11:31:33 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::restfreqs        Exception Reported: Exception: Spectral window ID -1 out of range, must be nonnegative and less than or equal to 39.
13-Nov-2025 11:31:33 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::restfreqs+        ... thrown by void casac::msmetadata::_checkSpwId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2443
13-Nov-2025 11:31:33 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::restfreqs        Exception Reported: Exception: SOURCE table does not contain a row with SOURCE_ID=50 and SPECTRAL_WINDOW_ID=0.
13-Nov-2025 11:31:33 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::restfreqs+        ... thrown by casac::variant* casac::msmetadata::restfreqs(long int, long int) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 1526
13-Nov-2025 11:31:33 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::restfreqs        Exception Reported: Exception: Spectral window ID 50 out of range, must be nonnegative and less than or equal to 39.
13-Nov-2025 11:31:33 2025-11-13 16:31:33        SEVERE        msmetadata_cmpt.cc::restfreqs+        ... thrown by void casac::msmetadata::_checkSpwId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2443
13-Nov-2025 11:31:33 test_tool_msmetadata.py::msmetadata_test::test_restfreqs PASSED
13-Nov-2025 11:31:33 2025-11-13 16:31:33        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:33 2025-11-13 16:31:34        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/uid___A002_Xa1f062_X37e3.ms...
13-Nov-2025 11:31:34 2025-11-13 16:31:34        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/uid___A002_X7b13df_X68f.ms...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_rxbands PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:34        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 2025-11-13 16:31:34        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_scannumbers PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:34        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 2025-11-13 16:31:34        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_scansforfield PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:34        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 2025-11-13 16:31:34        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_scansforintent PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_scansforspw PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_scansforstate PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_scansfortimes PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_schedule PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_sideband PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_sourcedirs PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 2025-11-13 16:31:35        SEVERE        msmetadata_cmpt.cc::sourceidforfield        Exception Reported: Exception: Antenna ID -1 out of range, must be less than 6.
13-Nov-2025 11:31:34 2025-11-13 16:31:35        SEVERE        msmetadata_cmpt.cc::sourceidforfield+        ... thrown by void casac::msmetadata::_checkFieldId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2425
13-Nov-2025 11:31:34 2025-11-13 16:31:35        SEVERE        msmetadata_cmpt.cc::sourceidforfield        Exception Reported: Exception: Antenna ID 6 out of range, must be less than 6.
13-Nov-2025 11:31:34 2025-11-13 16:31:35        SEVERE        msmetadata_cmpt.cc::sourceidforfield+        ... thrown by void casac::msmetadata::_checkFieldId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2425
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_sourceidforfield PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_sourceidsfromsourcetable PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_sourcenames PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_spwfordatadesc PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_spwsforbasebands PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:34 test_tool_msmetadata.py::msmetadata_test::test_spwsforfield PASSED
13-Nov-2025 11:31:34 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_spwsforintent PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 2025-11-13 16:31:35        WARN        msmetadata_cmpt.cc::spwsfornames        Specified spw named blah is not present in MS
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_spwsfornames PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 2025-11-13 16:31:35        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_spwsforscan PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 2025-11-13 16:31:35        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_statesforscan PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/uid___A002_Xa1f062_X37e3.ms...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_subwindows PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:35        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/uid___A002_Xd7be9d_X4838-spw16-18-20-22.ms...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_tdm_fdm PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_tdmspws PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_telescopenames PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_timerforobs PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_timesforfield PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_timesforintent PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_timesforscan PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:35 test_tool_msmetadata.py::msmetadata_test::test_timesforscans PASSED
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:35 2025-11-13 16:31:36        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
13-Nov-2025 11:31:36 test_tool_msmetadata.py::msmetadata_test::test_timesforspws PASSED
13-Nov-2025 11:31:36 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:36 2025-11-13 16:31:36        SEVERE        msmetadata_cmpt.cc::transitions        Exception Reported: Exception: sourceid cannot be negative.
13-Nov-2025 11:31:36 2025-11-13 16:31:36        SEVERE        msmetadata_cmpt.cc::transitions+        ... thrown by casac::variant* casac::msmetadata::transitions(long int, long int) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2270
13-Nov-2025 11:31:36 2025-11-13 16:31:36        SEVERE        msmetadata_cmpt.cc::transitions        Exception Reported: Exception: Spectral window ID -1 out of range, must be nonnegative and less than or equal to 39.
13-Nov-2025 11:31:36 2025-11-13 16:31:36        SEVERE        msmetadata_cmpt.cc::transitions+        ... thrown by void casac::msmetadata::_checkSpwId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2443
13-Nov-2025 11:31:36 2025-11-13 16:31:36        SEVERE        msmetadata_cmpt.cc::transitions        Exception Reported: Exception: SOURCE table does not contain a row with SOURCE_ID=50 and SPECTRAL_WINDOW_ID=0.
13-Nov-2025 11:31:36 2025-11-13 16:31:36        SEVERE        msmetadata_cmpt.cc::transitions+        ... thrown by casac::variant* casac::msmetadata::transitions(long int, long int) at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2277
13-Nov-2025 11:31:36 2025-11-13 16:31:36        SEVERE        msmetadata_cmpt.cc::transitions        Exception Reported: Exception: Spectral window ID 50 out of range, must be nonnegative and less than or equal to 39.
13-Nov-2025 11:31:36 2025-11-13 16:31:36        SEVERE        msmetadata_cmpt.cc::transitions+        ... thrown by void casac::msmetadata::_checkSpwId(int, bool) const at File: src/tools/msmetadata/msmetadata_cmpt.cc, line: 2443
13-Nov-2025 11:31:36 test_tool_msmetadata.py::msmetadata_test::test_transitions PASSED
13-Nov-2025 11:31:36 2025-11-13 16:31:36        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on /opt/casa/data/casatestdata/unittest/msmetadata/MSMetaData.ms...
13-Nov-2025 11:31:36 test_tool_msmetadata.py::msmetadata_test::test_wvrspws PASSED
13-Nov-2025 11:31:36
13-Nov-2025 11:31:36 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_msmetadata/nose.xml -
13-Nov-2025 11:31:36 ============================== 85 passed in 7.66s ==============================
13-Nov-2025 11:31:36 ============================= test session starts ==============================
13-Nov-2025 11:31:36 platform linux -- Python 3.12.9, pytest-9.0.1, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.12
13-Nov-2025 11:31:36 cachedir: .pytest_cache
13-Nov-2025 11:31:36 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation
13-Nov-2025 11:31:36 configfile: pytest.ini
13-Nov-2025 11:31:36 collecting ... collected 8 items
13-Nov-2025 11:31:36
13-Nov-2025 11:31:37 2025-11-13 16:31:37        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 10000 grid points in 2 planes.
13-Nov-2025 11:31:37 2025-11-13 16:31:37        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:37 2025-11-13 16:31:38        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 10000 grid points in 2 planes.
13-Nov-2025 11:31:37 2025-11-13 16:31:38        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:37 2025-11-13 16:31:38        INFO        name::        No table opened.
13-Nov-2025 11:31:37 2025-11-13 16:31:38        INFO        showcache::        The Table Cache is empty.
13-Nov-2025 11:31:37 2025-11-13 16:31:38        INFO        name::        No table opened.
13-Nov-2025 11:31:37 2025-11-13 16:31:38        INFO        name::        No table opened.
13-Nov-2025 11:31:37 test_tool_image_deviation.py::ia_deviation_test::test001 PASSED
13-Nov-2025 11:31:37 2025-11-13 16:31:38        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 10000 grid points in 2 planes.
13-Nov-2025 11:31:38 2025-11-13 16:31:38        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:38 2025-11-13 16:31:38        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 10000 grid points in 2 planes.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        name::        No table opened.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        showcache::        The Table Cache is empty.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        name::        No table opened.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        name::        No table opened.
13-Nov-2025 11:31:38 test_tool_image_deviation.py::ia_deviation_test::test002 PASSED
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 1156 grid points in 2 planes.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 1156 grid points in 2 planes.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:38 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 1156 grid points in 2 planes.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:38 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 1156 grid points in 2 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        showcache::        The Table Cache is empty.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        name::        No table opened.
13-Nov-2025 11:31:39 test_tool_image_deviation.py::ia_deviation_test::test003 PASSED
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 1089 grid points in 2 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 1089 grid points in 2 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 1089 grid points in 2 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 1089 grid points in 2 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        showcache::        The Table Cache is empty.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:39        INFO        name::        No table opened.
13-Nov-2025 11:31:39 test_tool_image_deviation.py::ia_deviation_test::test005 PASSED
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 10.001 pix x 10.001 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 11 pix x 11 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 64 grid points in 2 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageRegion::fromRecord        Selected bounding box :
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageRegion::fromRecord+            [5, 5, 0] to [85, 85, 1]  (00:03:00.021, -00.45.00.077, I to 23:57:39.990, +00.35.00.036, Q)
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 10.001 pix x 10.001 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 11 pix x 11 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 36 grid points in 2 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageRegion::fromRecord        Selected bounding box :
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageRegion::fromRecord+            [27, 27, 0] to [66, 66, 0]  (00:01:32.003, -00.23.00.010, I to 23:58:55.999, +00.16.00.003, I)
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageRegion::fromRecord        Selected bounding box :
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageRegion::fromRecord+            [22, 22, 0] to [61, 61, 0]  (00:01:32.003, -00.23.00.010, I to 23:58:55.999, +00.16.00.003, I)
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        showcache::        The Table Cache is empty.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 test_tool_image_deviation.py::ia_deviation_test::test006 PASSED
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageFactory::createImage        Created Paged image 'mycirc.im' of shape [100, 100] with float valued pixels.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::useReferencePixelAsAnchor        Anchor being set at pixel [50,50], at/near image reference pixel.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_computeStat        Using circular region of radius 20 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 41 pix x 41 pix and there are 1257 good pixels in the circle that are being used) to choose pixels for computing SUM using the classical algorithm around each of 25 grid points in 1 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_doInterpolation        Interpolate using CUBIC algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        showcache::        The Table Cache is empty.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 test_tool_image_deviation.py::ia_deviation_test::test_circle PASSED
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 10] with float valued pixels.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 9 grid points in 1 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 10, 2] with float valued pixels.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 9 grid points in 2 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        showcache::        The Table Cache is empty.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 test_tool_image_deviation.py::ia_deviation_test::test_mask PASSED
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        ImageFactory::createImage        Created Temp image  of shape [20, 20] with float valued pixels.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 25 grid points in 1 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_doInterpolation        Interpolate using CUBIC algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::useReferencePixelAsAnchor        Anchor being set at pixel [10,10], at/near image reference pixel.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 25 grid points in 1 planes.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        StatImageCreator::_doInterpolation        Interpolate using CUBIC algorithm.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        showcache::        The Table Cache is empty.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 2025-11-13 16:31:40        INFO        name::        No table opened.
13-Nov-2025 11:31:39 test_tool_image_deviation.py::ia_deviation_test::test_refpix PASSED
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_deviation/nose.xml -
13-Nov-2025 11:31:39 ============================== 8 passed in 3.18s ===============================
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_rebin/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_rebin
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_rebin/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_rebin/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_ms/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_regrid/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_simulator/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_transpose/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_transpose
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_transpose/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_transpose/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_table/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_table
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_table/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_table/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_sdm/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_fitprofile/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_fitprofile
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_fitprofile/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_fitprofile/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_vpmanager/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_vpmanager
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_vpmanager/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_vpmanager/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calanalysis/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_statistics/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_imagepol/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_measures/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_measures/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_createmultims/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_createmultims
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_ms_createmultims/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_createmultims/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_msmetadata/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_msmetadata/
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation/
13-Nov-2025 11:31:39 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation
13-Nov-2025 11:31:39
13-Nov-2025 11:31:39 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_deviation/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation/
13-Nov-2025 11:31:42 total 77004
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci 15001   138428 Nov 13 16:14 casatestutils-6.7.3.4a100.dev0+dirty-py3-none-any.whl
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci 15001 78544339 Nov 13 16:14 casatools-6.7.3.4a100.dev0+dirty-cp312-cp312-manylinux_2_28_x86_64.whl
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      4340 Nov 13 16:31 test_tool_agentflagger.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1006 Nov 13 16:31 test_tool_calanalysis.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      2658 Nov 13 16:31 test_tool_calibrater.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       687 Nov 13 16:31 test_tool_componentlist.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      7467 Nov 13 16:31 test_tool_coordsys.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root     12935 Nov 13 16:31 test_tool_image.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      2220 Nov 13 16:31 test_tool_image_collapse.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1048 Nov 13 16:31 test_tool_image_deviation.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      2575 Nov 13 16:31 test_tool_image_fitprofile.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1178 Nov 13 16:31 test_tool_image_pbcor.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       883 Nov 13 16:31 test_tool_image_pv.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1006 Nov 13 16:31 test_tool_image_rebin.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1336 Nov 13 16:31 test_tool_image_regrid.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1533 Nov 13 16:31 test_tool_image_statistics.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       765 Nov 13 16:31 test_tool_image_subimage.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       890 Nov 13 16:31 test_tool_image_transpose.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1966 Nov 13 16:31 test_tool_imagepol.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       839 Nov 13 16:31 test_tool_linearmosaic.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       669 Nov 13 16:31 test_tool_logsink.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       549 Nov 13 16:31 test_tool_measures.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      5224 Nov 13 16:31 test_tool_ms.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       367 Nov 13 16:31 test_tool_ms_createmultims.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      2095 Nov 13 16:31 test_tool_ms_statwt.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      8633 Nov 13 16:31 test_tool_msmetadata.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       359 Nov 13 16:31 test_tool_msuvbinner.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1171 Nov 13 16:31 test_tool_quanta.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      3977 Nov 13 16:31 test_tool_regionmanager.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      2072 Nov 13 16:31 test_tool_sdm.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      2096 Nov 13 16:31 test_tool_sdm_toms.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root       670 Nov 13 16:31 test_tool_simulator.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1717 Nov 13 16:31 test_tool_synthesisutils.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1462 Nov 13 16:31 test_tool_table.xml
13-Nov-2025 11:31:42 -rw-r--r-- 1 casaci root      1717 Nov 13 16:31 test_tool_vpmanager.xml
13-Nov-2025 11:31:42 Finished task 'Test wheel' with result: Success
13-Nov-2025 11:31:42 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
13-Nov-2025 11:31:42 Parsing test results under /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASASPECS-CI197-TTOML228...
13-Nov-2025 11:31:42 Finished task 'Parse tests' with result: Success
13-Nov-2025 11:31:42 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
13-Nov-2025 11:31:42
Beginning to execute external process for build 'CASASPECS - CI Build - CAS-14542 - Test Tools Many Linux 2.28 #3 (CASASPECS-CI197-TTOML228-3)'
... running command line:
/export/home/cbt-el7-2/bamboohome/temp/CASASPECS-CI197-TTOML228-3-ScriptBuildTask-17720170781754487176.sh
... in: /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASASPECS-CI197-TTOML228
13-Nov-2025 11:31:42 Starting cleanup
13-Nov-2025 11:31:54 wheel-container-test
13-Nov-2025 11:31:56 wheel-container-test
13-Nov-2025 11:31:56 Cleanup complete
13-Nov-2025 11:31:56 Finished task 'Cleanup' with result: Success
13-Nov-2025 11:31:56 Running post build plugin 'Docker Container Cleanup'
13-Nov-2025 11:31:56
Beginning to execute external process for build 'CASASPECS - CI Build - CAS-14542 - Test Tools Many Linux 2.28 #3 (CASASPECS-CI197-TTOML228-3)'
... running command line:
/usr/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-2/bamboohome/xml-data/build-dir/CASASPECS-CI197-TTOML228
13-Nov-2025 11:31:56 Error response from daemon: No such container: wheel-container-test
13-Nov-2025 11:31:56 Running post build plugin 'NCover Results Collector'
13-Nov-2025 11:31:56 Running post build plugin 'Build Results Label Collector'
13-Nov-2025 11:31:56 Running post build plugin 'Clover Results Collector'
13-Nov-2025 11:31:56 Running post build plugin 'npm Cache Cleanup'
13-Nov-2025 11:31:56 Running post build plugin 'Artifact Copier'
13-Nov-2025 11:31:56 Publishing an artifact: Tests
13-Nov-2025 11:31:57 Finished publishing of artifact Non required job artifact Http Compression On : [Tests], patterns: [*.xml] anchored at: [wheeldirectory/] in 1.053 s
13-Nov-2025 11:31:57 Publishing an artifact: xml Directories
13-Nov-2025 11:31:57 Finished publishing of artifact Non required job artifact Http Compression On : [xml Directories], patterns: [**/*.xml] anchored at: [wheeldirectory/] in 192.1 ms
13-Nov-2025 11:31:57 Finalising the build...
13-Nov-2025 11:31:57 Stopping timer.
13-Nov-2025 11:31:57 Build CASASPECS-CI197-TTOML228-3 completed.
13-Nov-2025 11:31:58 Running on server: post build plugin 'Build Hanging Detection Configuration'
13-Nov-2025 11:31:58 Running on server: post build plugin 'NCover Results Collector'
13-Nov-2025 11:31:58 Running on server: post build plugin 'Build Labeller'
13-Nov-2025 11:31:58 Running on server: post build plugin 'Clover Delta Calculator'
13-Nov-2025 11:31:58 Running on server: post build plugin 'Maven Dependencies Postprocessor'
13-Nov-2025 11:31:58 All post build plugins have finished
13-Nov-2025 11:31:58 Generating build results summary...
13-Nov-2025 11:31:58 Saving build results to disk...
13-Nov-2025 11:31:58 Store variable context...
13-Nov-2025 11:31:58 Finished building CASASPECS-CI197-TTOML228-3.