Build: #1 was successful

Job: Test Tools Many Linux 2.28 was successful

Stages & jobs

  1. Default Stage

  2. Test

Build log

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

31-May-2026 20:55:13 test_tool_calibrater.py::calibrater_test::test_selectVisTime
31-May-2026 20:55:13 Check that time is properly selected by selectvis
31-May-2026 20:55:13 PASSED
31-May-2026 20:55:13
31-May-2026 20:55:14 2026-06-01 00:55:14        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
31-May-2026 20:55:14 2026-06-01 00:55:14        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
31-May-2026 20:55:14 2026-06-01 00:55:14        INFO                Clearing all model records in MS header.
31-May-2026 20:55:14 2026-06-01 00:55:14        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:15 2026-06-01 00:55:15        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:15 2026-06-01 00:55:16        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
31-May-2026 20:55:15 2026-06-01 00:55:16        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:15 2026-06-01 00:55:16        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
31-May-2026 20:55:15 2026-06-01 00:55:16        INFO        calibrater::reset        Reseting solve/apply state
31-May-2026 20:55:15 2026-06-01 00:55:16        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
31-May-2026 20:55:15 2026-06-01 00:55:16        INFO        Calibrater::selectvis+         Selecting on uvrange: '> 500000lambda'
31-May-2026 20:55:16 2026-06-01 00:55:16        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 103321
31-May-2026 20:55:16 2026-06-01 00:55:16        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
31-May-2026 20:55:16 2026-06-01 00:55:16        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
31-May-2026 20:55:16 2026-06-01 00:55:16        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
31-May-2026 20:55:16 2026-06-01 00:55:16        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
31-May-2026 20:55:16 2026-06-01 00:55:16        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
31-May-2026 20:55:16 2026-06-01 00:55:16        INFO        calibrater::correct        Beginning correct---------------------------
31-May-2026 20:55:16 2026-06-01 00:55:16        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
31-May-2026 20:55:16 2026-06-01 00:55:16        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
31-May-2026 20:55:18 2026-06-01 00:55:18        INFO        calibrater::correct        Finished correcting.
31-May-2026 20:55:18 2026-06-01 00:55:19        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
31-May-2026 20:55:18 test_tool_calibrater.py::calibrater_test::test_selectVisUVrange
31-May-2026 20:55:18 Check that selectvis properly selects for uvrange
31-May-2026 20:55:18 PASSED
31-May-2026 20:55:18
31-May-2026 20:55:18 2026-06-01 00:55:19        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
31-May-2026 20:55:18 2026-06-01 00:55:19        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
31-May-2026 20:55:18 2026-06-01 00:55:19        INFO                Clearing all model records in MS header.
31-May-2026 20:55:18 2026-06-01 00:55:19        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:20 2026-06-01 00:55:20        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:20 2026-06-01 00:55:20        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
31-May-2026 20:55:20 2026-06-01 00:55:20        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:20 2026-06-01 00:55:20        INFO        calibrater::parsecallibfile        Beginning parsecallibfile-)-------
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO        calibrater::setcallib        Beginning setcallib---------
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO        Calibrater::setcallib2(callib)        Arranging to APPLY:
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO                .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO                .   0:
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO        +             MS: obs= scan= fld= intent= spw=
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO        +             CT: tinterp=linear finterp=
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO        +                 obsmap=[]         scanmap=[]         fldmap=[]
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO        +                 spwmap=[]         antmap=[]
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO        calibrater::corrupt        Beginning corrupt---------------------------
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
31-May-2026 20:55:20 2026-06-01 00:55:21        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 (by cal library) calWt=true
31-May-2026 20:55:22 2026-06-01 00:55:23        INFO        calibrater::corrupt        Finished corrupting.
31-May-2026 20:55:23 2026-06-01 00:55:24        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
31-May-2026 20:55:23 test_tool_calibrater.py::calibrater_test::test_setCalLib
31-May-2026 20:55:23 Check that a provided cal table can be used to corrupt the MODEL_DATA 
31-May-2026 20:55:23 PASSED
31-May-2026 20:55:23
31-May-2026 20:55:23 2026-06-01 00:55:24        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
31-May-2026 20:55:23 2026-06-01 00:55:24        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
31-May-2026 20:55:23 2026-06-01 00:55:24        INFO                Clearing all model records in MS header.
31-May-2026 20:55:23 2026-06-01 00:55:24        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:25 2026-06-01 00:55:25        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:25 2026-06-01 00:55:26        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
31-May-2026 20:55:25 2026-06-01 00:55:26        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:25 2026-06-01 00:55:26        INFO        Calibrater::setCorrDepFlags        Setting correlation dependent flags = True
31-May-2026 20:55:25 2026-06-01 00:55:26        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
31-May-2026 20:55:25 test_tool_calibrater.py::calibrater_test::test_setCorrDepFlags
31-May-2026 20:55:25 Check that corrdepflags will be checked 
31-May-2026 20:55:25 PASSED
31-May-2026 20:55:25
31-May-2026 20:55:25 2026-06-01 00:55:26        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
31-May-2026 20:55:25 2026-06-01 00:55:26        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
31-May-2026 20:55:25 2026-06-01 00:55:26        INFO                Clearing all model records in MS header.
31-May-2026 20:55:25 2026-06-01 00:55:26        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:26 2026-06-01 00:55:26        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:26 2026-06-01 00:55:26        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
31-May-2026 20:55:26 2026-06-01 00:55:26        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:26 2026-06-01 00:55:26        INFO        calibrater::smooth        Beginning smooth--(MSSelection version)-------
31-May-2026 20:55:26 2026-06-01 00:55:26        INFO        Calibrater::smooth        Beginning smoothing/interpolating method.
31-May-2026 20:55:26 2026-06-01 00:55:26        WARN                Found pre-v4.1 caltable (/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/ngc5921.gcal); attempting to update...
31-May-2026 20:55:26 2026-06-01 00:55:26        WARN        +        SUCCEEDED: trivial OBSERVATION/OBSERVATION_ID have been added.
31-May-2026 20:55:26 2026-06-01 00:55:26        INFO        Calibrater::smooth        Storing result in testcalout.cal
31-May-2026 20:55:26 2026-06-01 00:55:26        INFO                Writing solutions to table: testcalout.cal
31-May-2026 20:55:26 2026-06-01 00:55:26        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
31-May-2026 20:55:26 test_tool_calibrater.py::calibrater_test::test_smoothedCalTables
31-May-2026 20:55:26 Check that the smooth command creates a smoothed cal table 
31-May-2026 20:55:26 PASSED
31-May-2026 20:55:26
31-May-2026 20:55:26 2026-06-01 00:55:27        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO                Clearing all model records in MS header.
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        calibrater::setsolvebandpoly        Beginning setsolvebandpoly--(MSSelection version)-------
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        Calibrater::setsolvebandpoly        Arranging to SOLVE:
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        Calibrater::setsolvebandpoly        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        calibrater::solve        Beginning solve-----------------------------
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        Calibrater::solve        .   (None)
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        Calibrater::solve        The following calibration term is arranged for solve:
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        Calibrater::solve        .   BPOLY: table=bpoly append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode= solnorm=true normtype=MEAN
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        Calibrater::solve        Solving for BPOLY
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO                For solint = inf, found 7 solution intervals.
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
31-May-2026 20:55:26 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
31-May-2026 20:55:27 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
31-May-2026 20:55:27 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:27 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.059507/0.0681762/0.0765806
31-May-2026 20:55:27 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:27 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.336529/0.750193/1.6779
31-May-2026 20:55:27 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:27 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0630839/0.0689061/0.0756384
31-May-2026 20:55:27 2026-06-01 00:55:27        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 0.279482/0.74979/1.59355
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0672607/0.089081/0.111106
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.31075/3.32219/4.29305
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0652822/0.0889198/0.116912
31-May-2026 20:55:27 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.44269/3.26616/4.3519
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350681/0.587724/0.894558
31-May-2026 20:55:28 2026-06-01 00:55:28        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:30 2026-06-01 00:55:30        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.296/59.0077/111.454
31-May-2026 20:55:30 2026-06-01 00:55:30        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:30 2026-06-01 00:55:30        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.354236/0.581733/0.988903
31-May-2026 20:55:30 2026-06-01 00:55:30        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 17.2419/56.3249/117.264
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0850169/0.113968/0.15276
31-May-2026 20:55:32 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.67561/5.33243/8.17296
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0784236/0.114345/0.159908
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.46392/5.27232/7.20673
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0703936/0.102501/0.131676
31-May-2026 20:55:33 2026-06-01 00:55:33        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:33 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.98728/4.47977/5.84264
31-May-2026 20:55:33 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:33 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0751114/0.102994/0.138529
31-May-2026 20:55:33 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:33 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 3.18629/4.47068/5.8941
31-May-2026 20:55:33 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.350626/0.604424/0.974388
31-May-2026 20:55:34 2026-06-01 00:55:34        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:36 2026-06-01 00:55:36        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 21.2542/59.3511/120.719
31-May-2026 20:55:36 2026-06-01 00:55:36        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:36 2026-06-01 00:55:36        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.382806/0.598119/0.941571
31-May-2026 20:55:36 2026-06-01 00:55:36        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 20.0637/57.2112/118.101
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         THIS IS THE NEW MULTI-SPW-FLEXIBLE VERSION
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Fitting bandpass amplitude and phase polynomials.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for amplitude is 5
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Polynomial degree for phase is 7
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Spectral grid for fit will have 63 points spaced by 24.4141 kHz.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Polynomial solution will be valid over frequency range: 1.41267e+09-1.41418e+09 Hz.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Total bandwidth: 1538.09 kHz.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Found data for 351 baselines among 27 antennas.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0750931/0.0978998/0.125897
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.72932/3.98787/5.33715
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Fitting amplitude polynomial.
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Per-baseline RMS log(Amp) statistics: (min/mean/max) = 0.0658572/0.0972125/0.140794
31-May-2026 20:55:38 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Fitting phase polynomial.
31-May-2026 20:55:39 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Per baseline RMS phase (deg) statistics: (min/mean/max) = 2.97943/3.97841/5.72903
31-May-2026 20:55:39 2026-06-01 00:55:39        INFO        BJonesPoly::selfSolveOne()         Normalizing antenna-based solutions.
31-May-2026 20:55:39 2026-06-01 00:55:39        INFO        BJonesPoly::updateCalTable()         Storing calibration in table bpoly
31-May-2026 20:55:39 2026-06-01 00:55:39        INFO        Calibrater::solve          Found good BPOLY solutions in 7 slots.
31-May-2026 20:55:39 2026-06-01 00:55:39        INFO        calibrater::solve        Finished solving.
31-May-2026 20:55:39 2026-06-01 00:55:39        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
31-May-2026 20:55:39 test_tool_calibrater.py::calibrater_test::test_solveBandpass
31-May-2026 20:55:39 Check that solve band poly creates the output table
31-May-2026 20:55:39 PASSED
31-May-2026 20:55:39
31-May-2026 20:55:39 2026-06-01 00:55:39        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
31-May-2026 20:55:39 2026-06-01 00:55:39        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
31-May-2026 20:55:39 2026-06-01 00:55:39        INFO        calibrater::open        Opening MS: ngc5921.ms for calibration.
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO                Clearing all model records in MS header.
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        VisSetUtil::initScrCols        Initialized 22653 rows.
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        calibrater::setsolvegainspline        Beginning setsolvegainspline--(MSSelection version)-------
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        Calibrater::setsolvegainspline        Arranging to SOLVE:
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        Calibrater::setsolvegainspline        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        calibrater::solve        Beginning solve-----------------------------
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        Calibrater::solve        .   (None)
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        Calibrater::solve        The following calibration term is arranged for solve:
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        Calibrater::solve        .   GSPLINE: table=gainspline append=false solint=inf refantmode='flex' refant='none' minsnr=0 apmode=AMP solnorm=false
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Fitting time-dependent cubic splines.
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Solving for phase splines with splinetime= 10800
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Solving for amplitude splines with splinetime= 10800
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1331+30500002_0, spw= 0, nchan= 63
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Accumulating data for:  field= 1445+09900002_0, spw= 0, nchan= 63
31-May-2026 20:55:39 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Accumulating data for:  field= N5921_2, spw= 0, nchan= 63
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Number of timestamps in data = 60
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        GJonesSpline::getKnots()         Gridded splinetime = 5280 sec.
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Number of cubic spline control points = 4
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Number of cubic spline knots = 8
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Number of cubic spline segments = 1
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Fitting amplitude spline.
31-May-2026 20:55:40 test_tool_calibrater.py::calibrater_test::test_solveGainspline  DPBTRF ;problem leading minor           23
31-May-2026 20:55:40 DPBTRF ;problem leading minor           23
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Searching for and correcting phase-wraps on each baseline.
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        GJonesSpline::solve()         Fitting phase spline.
31-May-2026 20:55:40 DPBTRF ;problem leading minor           22
31-May-2026 20:55:40 DPBTRF ;problem leading minor           22
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        GJonesSpline::updateCalTable         Storing solutions in table gainspline
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        calibrater::solve        Finished solving.
31-May-2026 20:55:40 2026-06-01 00:55:40        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
31-May-2026 20:55:40
31-May-2026 20:55:40 Check that solve gain spline creates the output table 
31-May-2026 20:55:40 PASSED
31-May-2026 20:55:40
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO                Clearing all model records in MS header.
31-May-2026 20:55:40 2026-06-01 00:55:40        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO        calibrater::specifycal        Beginning specifycal-----------------------
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO                Creating G Jones table from specified parameters.
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO                Generating 'G' corrections.
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO                Specified parameter(s) (per spw and pol) repeated on all antennas.
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO                spwId=1 antId=<all> polId=<all> parameter= 3   (ip0,ip1 = [0, 0, 0],[1, 0, 9])
31-May-2026 20:55:41 2026-06-01 00:55:42        INFO                Writing solutions to table: testcalout.cal
31-May-2026 20:55:42 2026-06-01 00:55:42        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
31-May-2026 20:55:42 test_tool_calibrater.py::calibrater_test::test_specifyCal
31-May-2026 20:55:42 Check that specifycal can set values for specific spws and antennas
31-May-2026 20:55:42 PASSED
31-May-2026 20:55:42
31-May-2026 20:55:42 2026-06-01 00:55:42        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
31-May-2026 20:55:42 2026-06-01 00:55:42        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
31-May-2026 20:55:42 2026-06-01 00:55:42        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
31-May-2026 20:55:42 2026-06-01 00:55:42        INFO                Clearing all model records in MS header.
31-May-2026 20:55:42 2026-06-01 00:55:42        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:43 2026-06-01 00:55:44        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:43 2026-06-01 00:55:44        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
31-May-2026 20:55:43 2026-06-01 00:55:44        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::setsolve        Arranging to SOLVE:
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::setsolve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::state        The following calibration terms are arranged for apply:
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::state        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::state        The following calibration term is arranged for solve:
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::state        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        calibrater::solve        Beginning solve-----------------------------
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::solve        The following calibration terms are arranged for apply:
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::solve        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::solve        The following calibration term is arranged for solve:
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO        Calibrater::solve        .   Mf Mueller: table=output.ms append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO                Solving for Mf Mueller
31-May-2026 20:55:44 2026-06-01 00:55:44        INFO                For solint = inf, found 84 solution intervals.
31-May-2026 20:55:45 2026-06-01 00:55:46        INFO                  Found good Mf Mueller solutions in 84 intervals.
31-May-2026 20:55:45 2026-06-01 00:55:46        INFO                Writing solutions to table: output.ms
31-May-2026 20:55:46 2026-06-01 00:55:46        INFO        calibrater::solve        Finished solving.
31-May-2026 20:55:46 2026-06-01 00:55:46        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
31-May-2026 20:55:46 test_tool_calibrater.py::calibrater_test::test_standardPath
31-May-2026 20:55:46 open setapply setsolve state solve close 
31-May-2026 20:55:46 PASSED
31-May-2026 20:55:46
31-May-2026 20:55:46 2026-06-01 00:55:47        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
31-May-2026 20:55:46 2026-06-01 00:55:47        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
31-May-2026 20:55:46 2026-06-01 00:55:47        INFO                Clearing all model records in MS header.
31-May-2026 20:55:46 2026-06-01 00:55:47        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:47 2026-06-01 00:55:48        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        name::        No table opened.
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::        The Table Cache has the following 16 entries:
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            0: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            1: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            2: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            3: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            4: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            5: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            6: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            7: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            8: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            9: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            10: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            11: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            12: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            13: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            14: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::            15: "/home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        name::        No table opened.
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        showcache::        The Table Cache is empty.
31-May-2026 20:55:48 2026-06-01 00:55:48        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
31-May-2026 20:55:48 test_tool_calibrater.py::calibrater_test::test_takesMs
31-May-2026 20:55:48 Check that the calibrater tool can open and close an MS 
31-May-2026 20:55:48 PASSED
31-May-2026 20:55:48
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
31-May-2026 20:55:48 2026-06-01 00:55:48        INFO                Clearing all model records in MS header.
31-May-2026 20:55:48 2026-06-01 00:55:49        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
31-May-2026 20:55:49 2026-06-01 00:55:50        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
31-May-2026 20:55:50 2026-06-01 00:55:50        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
31-May-2026 20:55:50 2026-06-01 00:55:50        INFO        Calibrater::        Initializing nominal selection to the whole MS.
31-May-2026 20:55:50 2026-06-01 00:55:50        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
31-May-2026 20:55:50 2026-06-01 00:55:50        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
31-May-2026 20:55:50 2026-06-01 00:55:50        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
31-May-2026 20:55:50 2026-06-01 00:55:50        INFO        calibrater::correct        Beginning correct---------------------------
31-May-2026 20:55:50 2026-06-01 00:55:50        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
31-May-2026 20:55:50 2026-06-01 00:55:50        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
31-May-2026 20:55:52 2026-06-01 00:55:53        INFO        calibrater::correct        Finished correcting.
31-May-2026 20:55:52 2026-06-01 00:55:53        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
31-May-2026 20:55:53 test_tool_calibrater.py::calibrater_test::test_writeToCorrected
31-May-2026 20:55:53 Check that the tool writes to the CORRECTED_DATA column 
31-May-2026 20:55:53 PASSED
31-May-2026 20:55:53
31-May-2026 20:55:53 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml -
31-May-2026 20:55:53 =================== 24 passed, 1 warning in 99.62s (0:01:39) ===================
31-May-2026 20:55:53 ============================= test session starts ==============================
31-May-2026 20:55:53 platform linux -- Python 3.12.9, pytest-9.0.3, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.12
31-May-2026 20:55:53 cachedir: .pytest_cache
31-May-2026 20:55:53 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator
31-May-2026 20:55:53 configfile: pytest.ini
31-May-2026 20:55:53 collecting ... collected 2 items
31-May-2026 20:55:53
31-May-2026 20:55:53
31-May-2026 20:55:53 test_tool_simulator.py::sm_settrop_test::test_smsettrop
31-May-2026 20:55:53 SKIPPED (Skipping due to intermittent failures)
31-May-2026 20:55:53
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        NewMSSimulator::initAnt()         Using global coordinates for the antennas
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        NewMSSimulator::initAnt()         Added rows to ANTENNA table
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        MSsimulator::initFeeds()         Added rows to FEED table
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        MSsimulator::initSpWindows()         Creating new spectral window LBand, ID 1
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        simulator::settimes()        Times
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        simulator::settimes()+             Integration time 2000s
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        simulator::settimes()             Times will be interpreted as hour angles for first source
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        NewMSSimulator::observe()        First source: fake @ 19:53:50.00   +40.06.00.00       J2000
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        NewMSSimulator::observe()+        Full time range: 03-Oct-2019/21:16:01.8 -- 04-Oct-2019/07:16:01.8 TAI with int = 2000
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        NewMSSimulator::observe()        Calculating a total of 18 integrations
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::reset()         Resetting all visibility corruption components
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::reset()         Reset all image-plane corruption components
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        AgentFlagger::open        Table type is Measurement Set
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: unflag
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: unflag
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        FlagMSHandler::        Chunk = 1 [progress: 100%], Observation = 0, Array = 0, Scan = 1, Field = 0 (fake), Spw = 0, Channels = 5, Corrs = [ RR LL ], Total Rows = 6318
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (63180/63180)
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        FlagMSHandler::nextChunk         ====================================================================================
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Unflag::tableSummary        => Percentage of data un-flagged in table selection: 100%
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        AgentFlagger::run        => Writing flags to the MS
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        NewMSSimulator::NewMSSimulator(MeasurementSet& theMS)         Opening MeasurementSet /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator/myms.ms with 6318 rows
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        NewMSSimulator::NewMSSimulator(MeasurementSet& theMS)            last spectral window ID = 1
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::predict()         Predicting visibilities using model: [] and componentList: mycomplist.cl
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::predict()         The data column will be replaced
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::getVPRecord         Using default Voltage Patterns from the VPManager
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::getVPRecord         Temporary alert : The state of the vpmanager tool has been modified by loading these primary beam models. If any of your scripts rely on the vpmanager state being preserved throughout your CASA session, please use vp.saveastable() and vp.loadfromtable() as needed.
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::createSkyEquation()         Synthesis gridding
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::createSkyEquation()         Fourier transforms will use image centers as tangent points
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::reset()         Resetting all visibility corruption components
31-May-2026 20:55:54 2026-06-01 00:55:54        INFO        Simulator::reset()         Reset all image-plane corruption components
31-May-2026 20:55:54 test_tool_simulator.py::sm_predict_test::test_plp
31-May-2026 20:55:54 CAS-13439 verify support for plp, spectral curvature model
31-May-2026 20:55:54 PASSED
31-May-2026 20:55:54
31-May-2026 20:55:54 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_simulator/nose.xml -
31-May-2026 20:55:54 =========================== short test summary info ============================
31-May-2026 20:55:54 SKIPPED [1] test_tool_simulator.py:64: Skipping due to intermittent failures
31-May-2026 20:55:54 =================== 1 passed, 1 skipped, 1 warning in 1.02s ====================
31-May-2026 20:55:54 ============================= test session starts ==============================
31-May-2026 20:55:54 platform linux -- Python 3.12.9, pytest-9.0.3, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.12
31-May-2026 20:55:54 cachedir: .pytest_cache
31-May-2026 20:55:54 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_synthesisutils
31-May-2026 20:55:54 configfile: pytest.ini
31-May-2026 20:55:55 collecting ... collected 13 items
31-May-2026 20:55:55
31-May-2026 20:55:55
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set        Setting (global) restoring beam.
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set        Beam parameters:  Major          : 1 arcsec
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set+          Minor          : 1 arcsec
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set+          Position Angle : 0 deg
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SynthesisUtilMethods::fitPsfBeam        Fitting PSF beam for Imagestore : su_fitpsfbeam_test_mfs
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.012123
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SIImageStore::printBeamSet         Beam : 52.9301 arcsec, 49.1474 arcsec, -87.3942 deg
31-May-2026 20:55:55 test_tool_synthesisutils.py::fitpsfbeam_test::test_mfs
31-May-2026 20:55:55 Test that fitting of mfs psf works 
31-May-2026 20:55:55 PASSED
31-May-2026 20:55:55
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set        Setting (global) restoring beam.
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set        Beam parameters:  Major          : 1 arcsec
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set+          Minor          : 1 arcsec
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set+          Position Angle : 0 deg
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SIImageStore::Open existing Images         SumWt does not exist. Proceeding only with PSF
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SynthesisUtilMethods::fitPsfBeam        Fitting PSF beam for Imagestore : su_fitpsfbeam_test_mfs
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.017203
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SIImageStore::printBeamSet         Beam : 49.8956 arcsec, 46.8024 arcsec, -88.289 deg
31-May-2026 20:55:55 test_tool_synthesisutils.py::fitpsfbeam_test::test_mfs_largerpsfcutoff
31-May-2026 20:55:55 Test that psfcutoff with a valid (larger) number  works 
31-May-2026 20:55:55 PASSED
31-May-2026 20:55:55
31-May-2026 20:55:55 test_tool_synthesisutils.py::fitpsfbeam_test::test_mfs_wrong_nterms
31-May-2026 20:55:55 Test that it catches if nterms is inconsistent with input psf (nterms=2, for a single term  psf) 
31-May-2026 20:55:55 PASSED
31-May-2026 20:55:55
31-May-2026 20:55:55 2026-06-01 00:55:55        WARN        SynthesisUtilMethods::fitPsfBeam        psfcutoff must be >0 and <1
31-May-2026 20:55:55 2026-06-01 00:55:55        WARN        SynthesisUtilMethods::fitPsfBeam        psfcutoff must be >0 and <1
31-May-2026 20:55:55 test_tool_synthesisutils.py::fitpsfbeam_test::test_mfs_wrong_psfcutoff
31-May-2026 20:55:55 Test that psfcutoff is given outside the allowed range (1.0) 
31-May-2026 20:55:55 PASSED
31-May-2026 20:55:55
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set        Setting (global) restoring beam.
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set        Beam parameters:  Major          : 1 arcsec
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set+          Minor          : 1 arcsec
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        BeamManipulator::set+          Position Angle : 0 deg
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SIImageStoreMultiTerm::Open existing Images         SumWt.ttx do not exist. Proceeding only with PSFs
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SynthesisUtilMethods::fitPsfBeam        Fitting PSF beam for Imagestore : su_fitpsfbeam_test_mtmfs
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SIImageStore::getPSFGaussian         Time to fit Gaussian to PSF 0.009973
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        SIImageStore::printBeamSet         Beam : 52.9301 arcsec, 49.1474 arcsec, -87.3942 deg
31-May-2026 20:55:55 test_tool_synthesisutils.py::fitpsfbeam_test::test_mtmfs_nterms2
31-May-2026 20:55:55 Test that fitting of multiterm  psf works 
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        name::        No table opened.
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        name::        No table opened.
31-May-2026 20:55:55 PASSED
31-May-2026 20:55:55
31-May-2026 20:55:55 test_tool_synthesisutils.py::getoptimumsize_test::test_default
31-May-2026 20:55:55 Test default size
31-May-2026 20:55:55 PASSED
31-May-2026 20:55:55
31-May-2026 20:55:55 test_tool_synthesisutils.py::getoptimumsize_test::test_evenimsize
31-May-2026 20:55:55 Test even non-optimal number 
31-May-2026 20:55:55 PASSED
31-May-2026 20:55:55
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        name::        No table opened.
31-May-2026 20:55:55 2026-06-01 00:55:55        INFO        name::        No table opened.
31-May-2026 20:55:55 test_tool_synthesisutils.py::getoptimumsize_test::test_oddimsize
31-May-2026 20:55:55 Test odd non-optimal number
31-May-2026 20:55:55 PASSED
31-May-2026 20:55:55
31-May-2026 20:55:59 2026-06-01 00:56:00        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on twhya.short.ms...
31-May-2026 20:55:59 2026-06-01 00:56:00        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on twhya.short.ms...
31-May-2026 20:56:00 test_tool_synthesisutils.py::advisechansel_test::test_advisechanelsel_datasel
31-May-2026 20:56:00 Test that data selection parameters or given frequency range, etc is returned correctly
31-May-2026 20:56:00 PASSED
31-May-2026 20:56:00
31-May-2026 20:56:01 test_tool_synthesisutils.py::advisechansel_test::test_advisechanelsel_datasel_ephem
31-May-2026 20:56:01 Test that data selection parameters for given frequency range,etc for ephemeris object is returned correctly
31-May-2026 20:56:01 PASSED
31-May-2026 20:56:01
31-May-2026 20:56:02 2026-06-01 00:56:03        SEVERE                No MeasurementSet has been assigned, please give a valid ms in msname
31-May-2026 20:56:03 test_tool_synthesisutils.py::advisechansel_test::test_su_adivsechanel_defaults
31-May-2026 20:56:03 Test non specified parameter case for proper error/warning message 
31-May-2026 20:56:03 PASSED
31-May-2026 20:56:03
31-May-2026 20:56:04 test_tool_synthesisutils.py::advisechansel_test::test_su_adivsechansel_getfreqrange
31-May-2026 20:56:04 Test that frequency range for given data selections is returned correctly
31-May-2026 20:56:04 PASSED
31-May-2026 20:56:04
31-May-2026 20:56:05 test_tool_synthesisutils.py::advisechansel_test::test_su_advisechansel_getfreqrange_ephem
31-May-2026 20:56:05 Test that frequency range for given data selections for an ephemeris object is returned correctly
31-May-2026 20:56:05 2026-06-01 00:56:06        INFO        name::        No table opened.
31-May-2026 20:56:05 2026-06-01 00:56:06        INFO        name::        No table opened.
31-May-2026 20:56:05 PASSED
31-May-2026 20:56:05
31-May-2026 20:56:05 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_synthesisutils/nose.xml -
31-May-2026 20:56:05 ======================== 13 passed, 1 warning in 10.98s ========================
31-May-2026 20:56:06 ============================= test session starts ==============================
31-May-2026 20:56:06 platform linux -- Python 3.12.9, pytest-9.0.3, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.12
31-May-2026 20:56:06 cachedir: .pytest_cache
31-May-2026 20:56:06 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta
31-May-2026 20:56:06 configfile: pytest.ini
31-May-2026 20:56:06 collecting ... collected 9 items
31-May-2026 20:56:06
31-May-2026 20:56:06
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE                Exception Reported: Error in QuantumHolder::fromString with input string "bogus": Illegal input units or format
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE        +         in converting quantity
31-May-2026 20:56:06 test_tool_quanta.py::quanta_convert_test::test_exceptions
31-May-2026 20:56:06 Test various exception cases
31-May-2026 20:56:06 PASSED
31-May-2026 20:56:06
31-May-2026 20:56:06 test_tool_quanta.py::quanta_convert_test::test_valid
31-May-2026 20:56:06 Test specifying the quantity as a scalar
31-May-2026 20:56:06 PASSED
31-May-2026 20:56:06
31-May-2026 20:56:06 test_tool_quanta.py::quanta_convert_test::test_value_as_string
31-May-2026 20:56:06 Test specifying value as a string
31-May-2026 20:56:06 PASSED
31-May-2026 20:56:06
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE                Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE        +         in converting quantity
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE                Exception Reported: Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE        +         in converting quantity
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE                Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE        +         in converting quantity
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE                Exception Reported: Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE        +         in converting quantity
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE                Exception Reported: Unit::check Illegal unit string 'zz'
31-May-2026 20:56:06 2026-06-01 00:56:07        SEVERE                Exception Reported: Unit::check Illegal unit string 'zz'
31-May-2026 20:56:06 test_tool_quanta.py::quanta_quantity_test::test_exceptions
31-May-2026 20:56:06 Test various exception cases
31-May-2026 20:56:06 PASSED
31-May-2026 20:56:06
31-May-2026 20:56:06 test_tool_quanta.py::quanta_quantity_test::test_multidimensional_arra_value
31-May-2026 20:56:06 Test specifying the quantity as a multidimensional array
31-May-2026 20:56:06 PASSED
31-May-2026 20:56:06
31-May-2026 20:56:06 test_tool_quanta.py::quanta_quantity_test::test_scalar_value
31-May-2026 20:56:06 Test specifying the quantity as a scalar
31-May-2026 20:56:06 PASSED
31-May-2026 20:56:06
31-May-2026 20:56:06 test_tool_quanta.py::quanta_quantity_test::test_value_as_record
31-May-2026 20:56:06 Test value as record (eg already a quantity).
31-May-2026 20:56:06 This is essentially a reflection operator.
31-May-2026 20:56:06 PASSED
31-May-2026 20:56:06
31-May-2026 20:56:06 test_tool_quanta.py::quanta_quantity_test::test_value_as_string
31-May-2026 20:56:06 Test specifying value as a string
31-May-2026 20:56:06 PASSED
31-May-2026 20:56:06
31-May-2026 20:56:06 test_tool_quanta.py::quanta_quantity_test::test_vector_value
31-May-2026 20:56:06 Test specifying the quantity as a vector
31-May-2026 20:56:06 PASSED
31-May-2026 20:56:06
31-May-2026 20:56:06 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_quanta/nose.xml -
31-May-2026 20:56:06 ========================= 9 passed, 1 warning in 0.52s =========================
31-May-2026 20:56:07 ============================= test session starts ==============================
31-May-2026 20:56:07 platform linux -- Python 3.12.9, pytest-9.0.3, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.12
31-May-2026 20:56:07 cachedir: .pytest_cache
31-May-2026 20:56:07 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_table
31-May-2026 20:56:07 configfile: pytest.ini
31-May-2026 20:56:07 collecting ... table tool tests will use /opt/casa/data/casatestdata/unittest/table/n08c1_swap1.ms
31-May-2026 20:56:07 collected 13 items
31-May-2026 20:56:07
31-May-2026 20:56:07
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableGetcoliterTest::test_consistency
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableGetcoliterTest::test_element_match
31-May-2026 20:56:08 Test element match using direct comparison and StopIteration assertion. 
31-May-2026 20:56:08 [4.71188568e+09 4.71188569e+09 4.71188570e+09 4.71188571e+09
31-May-2026 20:56:08 4.71188571e+09 4.71188572e+09 4.71188573e+09 4.71188574e+09]
31-May-2026 20:56:08 [4.71188568e+09 4.71188569e+09 4.71188570e+09 4.71188571e+09
31-May-2026 20:56:08 4.71188571e+09 4.71188572e+09 4.71188573e+09 4.71188574e+09]
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableGetcoliterTest::test_errors
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableGetcoliterTest::test_values
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableRowTest::test_all_rows
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableRowTest::test_columnnames_exclude
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableRowTest::test_columnnames_include
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableRowTest::test_get
31-May-2026 20:56:08 Test get function
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableRowTest::test_put
31-May-2026 20:56:08 Test put function
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableRowTest::test_shape
31-May-2026 20:56:08 Test for valid data shape
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:08        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::TableRowTest::test_some_rows
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:09        INFO        ImageFactory::createImage        Created Paged image 'tmp.im' of shape [3, 4] with float valued pixels.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::bogus        Exception Reported: Table column bogus is unknown
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: TableColumn: row number 1 exceeds #rows 1 in table /home/casatest/casa6/casatestutils/nosedir/test_tool_table/tmp.im
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: Exception: blc must have length of 2.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map+        ... thrown by void casac::table::_checkCorner(const std::vector<long int>&, const casacore::String&, const casacore::IPosition&, const std::pair<std::vector<long int>, std::vector<long int> >* const&) at File: src/tools/table/table_cmpt.cc, line: 1386
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: Exception: All elements of blc must be greater than or equal to 0.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map+        ... thrown by void casac::table::_checkCorner(const std::vector<long int>&, const casacore::String&, const casacore::IPosition&, const std::pair<std::vector<long int>, std::vector<long int> >* const&) at File: src/tools/table/table_cmpt.cc, line: 1390
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: Exception: Element 1 of blc must be less than 4.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map+        ... thrown by void casac::table::_checkCorner(const std::vector<long int>&, const casacore::String&, const casacore::IPosition&, const std::pair<std::vector<long int>, std::vector<long int> >* const&) at File: src/tools/table/table_cmpt.cc, line: 1395
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: Exception: trc must have length of 2.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map+        ... thrown by void casac::table::_checkCorner(const std::vector<long int>&, const casacore::String&, const casacore::IPosition&, const std::pair<std::vector<long int>, std::vector<long int> >* const&) at File: src/tools/table/table_cmpt.cc, line: 1386
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: Exception: All elements of trc must be greater than or equal to 0.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map+        ... thrown by void casac::table::_checkCorner(const std::vector<long int>&, const casacore::String&, const casacore::IPosition&, const std::pair<std::vector<long int>, std::vector<long int> >* const&) at File: src/tools/table/table_cmpt.cc, line: 1390
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: Exception: Element 0 of trc must be less than 3.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map+        ... thrown by void casac::table::_checkCorner(const std::vector<long int>&, const casacore::String&, const casacore::IPosition&, const std::pair<std::vector<long int>, std::vector<long int> >* const&) at File: src/tools/table/table_cmpt.cc, line: 1395
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: Exception: All elements of trc must be greater than or equal to their corresponding blc elements.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map+        ... thrown by void casac::table::_checkCorner(const std::vector<long int>&, const casacore::String&, const casacore::IPosition&, const std::pair<std::vector<long int>, std::vector<long int> >* const&) at File: src/tools/table/table_cmpt.cc, line: 1405
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: Exception: incr must have length of 2.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map+        ... thrown by casac::variant* casac::table::getcellslice(const string&, long int, const std::vector<long int>&, const std::vector<long int>&, const std::vector<long int>&) at File: src/tools/table/table_cmpt.cc, line: 1466
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map        Exception Reported: Exception: All elements of incr must be greater than 0.
31-May-2026 20:56:08 2026-06-01 00:56:09        SEVERE        getcellslice::map+        ... thrown by casac::variant* casac::table::getcellslice(const string&, long int, const std::vector<long int>&, const std::vector<long int>&, const std::vector<long int>&) at File: src/tools/table/table_cmpt.cc, line: 1471
31-May-2026 20:56:08 2026-06-01 00:56:09        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:09        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::table_getcellslice_test::test_exceptions
31-May-2026 20:56:08 Test various exception cases
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 2026-06-01 00:56:09        INFO        ImageFactory::createImage        Created Paged image 'tmp.im' of shape [3, 4] with float valued pixels.
31-May-2026 20:56:08 2026-06-01 00:56:09        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:09        INFO        name::        No table opened.
31-May-2026 20:56:08 2026-06-01 00:56:09        INFO        name::        No table opened.
31-May-2026 20:56:08 test_tool_table.py::table_getcellslice_test::test_getcellslice
31-May-2026 20:56:08 tests for valid inputs
31-May-2026 20:56:08 PASSED
31-May-2026 20:56:08
31-May-2026 20:56:08 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_table/nose.xml -
31-May-2026 20:56:08 ======================== 13 passed, 1 warning in 1.58s =========================
31-May-2026 20:56:08 ============================= test session starts ==============================
31-May-2026 20:56:08 platform linux -- Python 3.12.9, pytest-9.0.3, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.12
31-May-2026 20:56:08 cachedir: .pytest_cache
31-May-2026 20:56:08 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation
31-May-2026 20:56:08 configfile: pytest.ini
31-May-2026 20:56:09 collecting ... collected 8 items
31-May-2026 20:56:09
31-May-2026 20:56:09
31-May-2026 20:56:09 2026-06-01 00:56:09        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.
31-May-2026 20:56:09 2026-06-01 00:56:10        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:09 2026-06-01 00:56:10        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.
31-May-2026 20:56:10 2026-06-01 00:56:10        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:10 2026-06-01 00:56:10        INFO        name::        No table opened.
31-May-2026 20:56:10 2026-06-01 00:56:10        INFO        showcache::        The Table Cache is empty.
31-May-2026 20:56:10 2026-06-01 00:56:10        INFO        name::        No table opened.
31-May-2026 20:56:10 2026-06-01 00:56:10        INFO        name::        No table opened.
31-May-2026 20:56:10 test_tool_image_deviation.py::ia_deviation_test::test001
31-May-2026 20:56:10 Every pixel is a grid point
31-May-2026 20:56:10 PASSED
31-May-2026 20:56:10
31-May-2026 20:56:10 2026-06-01 00:56:10        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.
31-May-2026 20:56:10 2026-06-01 00:56:11        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:10 2026-06-01 00:56:11        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.
31-May-2026 20:56:11 2026-06-01 00:56:11        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        showcache::        The Table Cache is empty.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        name::        No table opened.
31-May-2026 20:56:11 test_tool_image_deviation.py::ia_deviation_test::test002
31-May-2026 20:56:11 Every pixel is a grid point with an offset, so should be the same result
31-May-2026 20:56:11 as test001
31-May-2026 20:56:11 PASSED
31-May-2026 20:56:11
31-May-2026 20:56:11 2026-06-01 00:56:11        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.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:11        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:11        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.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:11        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:11        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:11        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.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:11        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:11        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.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:11        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        showcache::        The Table Cache is empty.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        name::        No table opened.
31-May-2026 20:56:11 test_tool_image_deviation.py::ia_deviation_test::test003
31-May-2026 20:56:11 Every third pixel is a grid point
31-May-2026 20:56:11 PASSED
31-May-2026 20:56:11
31-May-2026 20:56:11 2026-06-01 00:56:11        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.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:11        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:11        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.
31-May-2026 20:56:11 2026-06-01 00:56:11        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:11        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:11        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.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:12        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:12        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.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:12        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        showcache::        The Table Cache is empty.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 test_tool_image_deviation.py::ia_deviation_test::test005
31-May-2026 20:56:11 Every third pixel is a grid point, using anchor [2,2]
31-May-2026 20:56:11 PASSED
31-May-2026 20:56:11
31-May-2026 20:56:11 2026-06-01 00:56:12        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.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        ImageRegion::fromRecord        Selected bounding box :
31-May-2026 20:56:11 2026-06-01 00:56:12        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)
31-May-2026 20:56:11 2026-06-01 00:56:12        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.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        ImageRegion::fromRecord        Selected bounding box :
31-May-2026 20:56:11 2026-06-01 00:56:12        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)
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        ImageRegion::fromRecord        Selected bounding box :
31-May-2026 20:56:11 2026-06-01 00:56:12        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)
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        showcache::        The Table Cache is empty.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 test_tool_image_deviation.py::ia_deviation_test::test006
31-May-2026 20:56:11 Test that regions work as expected
31-May-2026 20:56:11 PASSED
31-May-2026 20:56:11
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        ImageFactory::createImage        Created Paged image 'mycirc.im' of shape [100, 100] with float valued pixels.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::useReferencePixelAsAnchor        Anchor being set at pixel [50,50], at/near image reference pixel.
31-May-2026 20:56:11 2026-06-01 00:56:12        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.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::_doInterpolation        Interpolate using CUBIC algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        showcache::        The Table Cache is empty.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 test_tool_image_deviation.py::ia_deviation_test::test_circle
31-May-2026 20:56:11 test circles work correctly CAS-10296
31-May-2026 20:56:11 PASSED
31-May-2026 20:56:11
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 10] with float valued pixels.
31-May-2026 20:56:11 2026-06-01 00:56:12        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.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 10, 2] with float valued pixels.
31-May-2026 20:56:11 2026-06-01 00:56:12        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.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        showcache::        The Table Cache is empty.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 test_tool_image_deviation.py::ia_deviation_test::test_mask
31-May-2026 20:56:11 PASSED
31-May-2026 20:56:11
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        ImageFactory::createImage        Created Temp image  of shape [20, 20] with float valued pixels.
31-May-2026 20:56:11 2026-06-01 00:56:12        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.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::_doInterpolation        Interpolate using CUBIC algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::useReferencePixelAsAnchor        Anchor being set at pixel [10,10], at/near image reference pixel.
31-May-2026 20:56:11 2026-06-01 00:56:12        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.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        StatImageCreator::_doInterpolation        Interpolate using CUBIC algorithm.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        showcache::        The Table Cache is empty.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 2026-06-01 00:56:12        INFO        name::        No table opened.
31-May-2026 20:56:11 test_tool_image_deviation.py::ia_deviation_test::test_refpix
31-May-2026 20:56:11 Test using reference pixel
31-May-2026 20:56:11 PASSED
31-May-2026 20:56:11
31-May-2026 20:56:11 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_deviation/nose.xml -
31-May-2026 20:56:11 ========================= 8 passed, 1 warning in 2.74s =========================
31-May-2026 20:56:11
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_statwt/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_statwt
31-May-2026 20:56:11
31-May-2026 20:56:11 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_ms_statwt/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_statwt/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_linearmosaic/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_linearmosaic
31-May-2026 20:56:11
31-May-2026 20:56:11 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_linearmosaic/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_linearmosaic/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pv/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pv
31-May-2026 20:56:11
31-May-2026 20:56:11 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_pv/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pv/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_imagepol
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_createmultims/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_createmultims
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_rebin/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_rebin
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager
31-May-2026 20:56:11
31-May-2026 20:56:11 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_regionmanager/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage
31-May-2026 20:56:11
31-May-2026 20:56:11 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_subimage/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_coordsys/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_coordsys
31-May-2026 20:56:11
31-May-2026 20:56:11 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_coordsys/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_coordsys/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_synthesisutils/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_synthesisutils
31-May-2026 20:56:11
31-May-2026 20:56:11 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_synthesisutils/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_synthesisutils/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta
31-May-2026 20:56:11
31-May-2026 20:56:11 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_quanta/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_table/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_table
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:11
31-May-2026 20:56:11 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation/
31-May-2026 20:56:11 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation
31-May-2026 20:56:11
31-May-2026 20:56:11 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/
31-May-2026 20:56:14 total 77796
31-May-2026 20:56:14 -rw-r--r-- 1 casaci 15001   160860 Jun  1 00:35 casatestutils-6.7.6.5a100.dev0+dirty-py3-none-any.whl
31-May-2026 20:56:14 -rw-r--r-- 1 casaci 15001 79327951 Jun  1 00:35 casatools-6.7.6.5a100.dev0+dirty-cp312-cp312-manylinux_2_28_x86_64.whl
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      4340 Jun  1 00:56 test_tool_agentflagger.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1006 Jun  1 00:56 test_tool_calanalysis.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      2657 Jun  1 00:56 test_tool_calibrater.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       687 Jun  1 00:56 test_tool_componentlist.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      7467 Jun  1 00:56 test_tool_coordsys.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root     12935 Jun  1 00:56 test_tool_image.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      2220 Jun  1 00:56 test_tool_image_collapse.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1048 Jun  1 00:56 test_tool_image_deviation.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      2575 Jun  1 00:56 test_tool_image_fitprofile.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1178 Jun  1 00:56 test_tool_image_pbcor.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       883 Jun  1 00:56 test_tool_image_pv.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1006 Jun  1 00:56 test_tool_image_rebin.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1336 Jun  1 00:56 test_tool_image_regrid.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1641 Jun  1 00:56 test_tool_image_statistics.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       765 Jun  1 00:56 test_tool_image_subimage.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       890 Jun  1 00:56 test_tool_image_transpose.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1966 Jun  1 00:56 test_tool_imagepol.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       839 Jun  1 00:56 test_tool_linearmosaic.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       669 Jun  1 00:56 test_tool_logsink.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       549 Jun  1 00:56 test_tool_measures.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      5224 Jun  1 00:56 test_tool_ms.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       369 Jun  1 00:56 test_tool_ms_createmultims.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      2095 Jun  1 00:56 test_tool_ms_statwt.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      8633 Jun  1 00:56 test_tool_msmetadata.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       359 Jun  1 00:56 test_tool_msuvbinner.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1171 Jun  1 00:56 test_tool_quanta.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      3977 Jun  1 00:56 test_tool_regionmanager.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      2072 Jun  1 00:56 test_tool_sdm.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      2096 Jun  1 00:56 test_tool_sdm_toms.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root       670 Jun  1 00:56 test_tool_simulator.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1717 Jun  1 00:56 test_tool_synthesisutils.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1462 Jun  1 00:56 test_tool_table.xml
31-May-2026 20:56:14 -rw-r--r-- 1 casaci root      1717 Jun  1 00:56 test_tool_vpmanager.xml
31-May-2026 20:56:14 Finished task 'Test wheel' with result: Success
31-May-2026 20:56:14 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
31-May-2026 20:56:14 Parsing test results under /export/home/cbt-el7-6/bamboohome/xml-data/build-dir/CASASPECS-CI268-TTOML228...
31-May-2026 20:56:14 Finished task 'Parse tests' with result: Success
31-May-2026 20:56:14 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
31-May-2026 20:56:14
Beginning to execute external process for build 'CASASPECS - CI Build - CAS-14782 - Test Tools Many Linux 2.28 #1 (CASASPECS-CI268-TTOML228-1)'
... running command line:
/export/home/cbt-el7-6/bamboohome/temp/CASASPECS-CI268-TTOML228-1-ScriptBuildTask-1542933221686747533.sh
... in: /export/home/cbt-el7-6/bamboohome/xml-data/build-dir/CASASPECS-CI268-TTOML228
31-May-2026 20:56:14 Starting cleanup
31-May-2026 20:56:25 wheel-container-test
31-May-2026 20:56:26 wheel-container-test
31-May-2026 20:56:26 Cleanup complete
31-May-2026 20:56:26 Finished task 'Cleanup' with result: Success
31-May-2026 20:56:26 Running post build plugin 'Docker Container Cleanup'
31-May-2026 20:56:26
Beginning to execute external process for build 'CASASPECS - CI Build - CAS-14782 - Test Tools Many Linux 2.28 #1 (CASASPECS-CI268-TTOML228-1)'
... running command line:
/usr/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-6/bamboohome/xml-data/build-dir/CASASPECS-CI268-TTOML228
31-May-2026 20:56:26 Error response from daemon: No such container: wheel-container-test
31-May-2026 20:56:26 Running post build plugin 'NCover Results Collector'
31-May-2026 20:56:26 Running post build plugin 'Build Results Label Collector'
31-May-2026 20:56:26 Running post build plugin 'Clover Results Collector'
31-May-2026 20:56:27 Running post build plugin 'npm Cache Cleanup'
31-May-2026 20:56:27 Running post build plugin 'Artifact Copier'
31-May-2026 20:56:27 Publishing an artifact: Tests
31-May-2026 20:56:27 Finished publishing of artifact Non required job artifact Http Compression On : [Tests], patterns: [*.xml] anchored at: [wheeldirectory/] in 324.6 ms
31-May-2026 20:56:27 Publishing an artifact: xml Directories
31-May-2026 20:56:27 Finished publishing of artifact Non required job artifact Http Compression On : [xml Directories], patterns: [**/*.xml] anchored at: [wheeldirectory/] in 162.3 ms
31-May-2026 20:56:27 Finalising the build...
31-May-2026 20:56:27 Stopping timer.
31-May-2026 20:56:27 Build CASASPECS-CI268-TTOML228-1 completed.
31-May-2026 20:56:27 Running on server: post build plugin 'Build Hanging Detection Configuration'
31-May-2026 20:56:27 Running on server: post build plugin 'NCover Results Collector'
31-May-2026 20:56:27 Running on server: post build plugin 'Build Labeller'
31-May-2026 20:56:27 Running on server: post build plugin 'Clover Delta Calculator'
31-May-2026 20:56:27 Running on server: post build plugin 'Maven Dependencies Postprocessor'
31-May-2026 20:56:27 All post build plugins have finished
31-May-2026 20:56:27 Generating build results summary...
31-May-2026 20:56:28 Saving build results to disk...
31-May-2026 20:56:28 Store variable context...
31-May-2026 20:56:28 Finished building CASASPECS-CI268-TTOML228-1.