Build: #4 did not complete

Job: Test Tools macOS 15 Py3.12 did not complete

Build log

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

25-Aug-2025 12:29:20 Testing column POL_RESPONSE
25-Aug-2025 12:29:20 Column POL_RESPONSE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column RECEPTOR_ANGLE
25-Aug-2025 12:29:20 Column RECEPTOR_ANGLE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column ANTENNA_ID
25-Aug-2025 12:29:20 Column ANTENNA_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column BEAM_ID
25-Aug-2025 12:29:20 Column BEAM_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FEED_ID
25-Aug-2025 12:29:20 Column FEED_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column INTERVAL
25-Aug-2025 12:29:20 Column INTERVAL PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NUM_RECEPTORS
25-Aug-2025 12:29:20 Column NUM_RECEPTORS PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SPECTRAL_WINDOW_ID
25-Aug-2025 12:29:20 Column SPECTRAL_WINDOW_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TIME
25-Aug-2025 12:29:20 Column TIME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FOCUS_LENGTH
25-Aug-2025 12:29:20 Column FOCUS_LENGTH PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable FIELD
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column CODE
25-Aug-2025 12:29:20 Column CODE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FLAG_ROW
25-Aug-2025 12:29:20 Column FLAG_ROW PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NAME
25-Aug-2025 12:29:20 Column NAME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NUM_POLY
25-Aug-2025 12:29:20 Column NUM_POLY PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SOURCE_ID
25-Aug-2025 12:29:20 Column SOURCE_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TIME
25-Aug-2025 12:29:20 Column TIME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column EPHEMERIS_ID
25-Aug-2025 12:29:20 Column EPHEMERIS_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column PHASE_DIR
25-Aug-2025 12:29:20 Column PHASE_DIR PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column PhaseDir_Ref
25-Aug-2025 12:29:20 Column PhaseDir_Ref PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column DELAY_DIR
25-Aug-2025 12:29:20 Column DELAY_DIR PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column DelayDir_Ref
25-Aug-2025 12:29:20 Column DelayDir_Ref PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column REFERENCE_DIR
25-Aug-2025 12:29:20 Column REFERENCE_DIR PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column RefDir_Ref
25-Aug-2025 12:29:20 Column RefDir_Ref PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable FLAG_CMD
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column APPLIED
25-Aug-2025 12:29:20 Column APPLIED PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column COMMAND
25-Aug-2025 12:29:20 Column COMMAND PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column INTERVAL
25-Aug-2025 12:29:20 Column INTERVAL PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column LEVEL
25-Aug-2025 12:29:20 Column LEVEL PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column REASON
25-Aug-2025 12:29:20 Column REASON PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SEVERITY
25-Aug-2025 12:29:20 Column SEVERITY PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TIME
25-Aug-2025 12:29:20 Column TIME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TYPE
25-Aug-2025 12:29:20 Column TYPE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable OBSERVATION
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TIME_RANGE
25-Aug-2025 12:29:20 Column TIME_RANGE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column LOG
25-Aug-2025 12:29:20 Column LOG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SCHEDULE
25-Aug-2025 12:29:20 Column SCHEDULE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FLAG_ROW
25-Aug-2025 12:29:20 Column FLAG_ROW PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column OBSERVER
25-Aug-2025 12:29:20 Column OBSERVER PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column PROJECT
25-Aug-2025 12:29:20 Column PROJECT PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column RELEASE_DATE
25-Aug-2025 12:29:20 Column RELEASE_DATE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SCHEDULE_TYPE
25-Aug-2025 12:29:20 Column SCHEDULE_TYPE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TELESCOPE_NAME
25-Aug-2025 12:29:20 Column TELESCOPE_NAME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable POLARIZATION
25-Aug-2025 12:29:20 Column CORR_TYPE of reference.ms/POLARIZATION and uid___A002_X6218fb_X264.ms/POLARIZATION agree
25-Aug-2025 12:29:20 Column CORR_PRODUCT of reference.ms/POLARIZATION and uid___A002_X6218fb_X264.ms/POLARIZATION agree
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FLAG_ROW
25-Aug-2025 12:29:20 Column FLAG_ROW PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NUM_CORR
25-Aug-2025 12:29:20 Column NUM_CORR PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable PROCESSOR
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FLAG_ROW
25-Aug-2025 12:29:20 Column FLAG_ROW PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column MODE_ID
25-Aug-2025 12:29:20 Column MODE_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TYPE
25-Aug-2025 12:29:20 Column TYPE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TYPE_ID
25-Aug-2025 12:29:20 Column TYPE_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SUB_TYPE
25-Aug-2025 12:29:20 Column SUB_TYPE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable SOURCE
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column DIRECTION
25-Aug-2025 12:29:20 Column DIRECTION PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column PROPER_MOTION
25-Aug-2025 12:29:20 Column PROPER_MOTION PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column CALIBRATION_GROUP
25-Aug-2025 12:29:20 Column CALIBRATION_GROUP PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column CODE
25-Aug-2025 12:29:20 Column CODE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column INTERVAL
25-Aug-2025 12:29:20 Column INTERVAL PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NAME
25-Aug-2025 12:29:20 Column NAME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NUM_LINES
25-Aug-2025 12:29:20 Column NUM_LINES PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SOURCE_ID
25-Aug-2025 12:29:20 Column SOURCE_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SPECTRAL_WINDOW_ID
25-Aug-2025 12:29:20 Column SPECTRAL_WINDOW_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TIME
25-Aug-2025 12:29:20 Column TIME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable SPECTRAL_WINDOW
25-Aug-2025 12:29:20 Column CHAN_FREQ of reference.ms/SPECTRAL_WINDOW and uid___A002_X6218fb_X264.ms/SPECTRAL_WINDOW agree
25-Aug-2025 12:29:20 Column CHAN_WIDTH of reference.ms/SPECTRAL_WINDOW and uid___A002_X6218fb_X264.ms/SPECTRAL_WINDOW agree
25-Aug-2025 12:29:20 Column EFFECTIVE_BW of reference.ms/SPECTRAL_WINDOW and uid___A002_X6218fb_X264.ms/SPECTRAL_WINDOW agree
25-Aug-2025 12:29:20 Column RESOLUTION of reference.ms/SPECTRAL_WINDOW and uid___A002_X6218fb_X264.ms/SPECTRAL_WINDOW agree
25-Aug-2025 12:29:20 Column ASSOC_SPW_ID of reference.ms/SPECTRAL_WINDOW and uid___A002_X6218fb_X264.ms/SPECTRAL_WINDOW agree
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column MEAS_FREQ_REF
25-Aug-2025 12:29:20 Column MEAS_FREQ_REF PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column REF_FREQUENCY
25-Aug-2025 12:29:20 Column REF_FREQUENCY PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FLAG_ROW
25-Aug-2025 12:29:20 Column FLAG_ROW PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FREQ_GROUP
25-Aug-2025 12:29:20 Column FREQ_GROUP PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FREQ_GROUP_NAME
25-Aug-2025 12:29:20 Column FREQ_GROUP_NAME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column IF_CONV_CHAIN
25-Aug-2025 12:29:20 Column IF_CONV_CHAIN PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NAME
25-Aug-2025 12:29:20 Column NAME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NET_SIDEBAND
25-Aug-2025 12:29:20 Column NET_SIDEBAND PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NUM_CHAN
25-Aug-2025 12:29:20 Column NUM_CHAN PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TOTAL_BANDWIDTH
25-Aug-2025 12:29:20 Column TOTAL_BANDWIDTH PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column BBC_NO
25-Aug-2025 12:29:20 Column BBC_NO PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SDM_WINDOW_FUNCTION
25-Aug-2025 12:29:20 Column SDM_WINDOW_FUNCTION PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SDM_NUM_BIN
25-Aug-2025 12:29:20 Column SDM_NUM_BIN PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SDM_CORR_BIT
25-Aug-2025 12:29:20 Column SDM_CORR_BIT PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable STATE
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column CAL
25-Aug-2025 12:29:20 Column CAL PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FLAG_ROW
25-Aug-2025 12:29:20 Column FLAG_ROW PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column LOAD
25-Aug-2025 12:29:20 Column LOAD PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column OBS_MODE
25-Aug-2025 12:29:20 Column OBS_MODE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column REF
25-Aug-2025 12:29:20 Column REF PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SIG
25-Aug-2025 12:29:20 Column SIG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SUB_SCAN
25-Aug-2025 12:29:20 Column SUB_SCAN PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable SYSCAL
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column ANTENNA_ID
25-Aug-2025 12:29:20 Column ANTENNA_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column FEED_ID
25-Aug-2025 12:29:20 Column FEED_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column INTERVAL
25-Aug-2025 12:29:20 Column INTERVAL PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column SPECTRAL_WINDOW_ID
25-Aug-2025 12:29:20 Column SPECTRAL_WINDOW_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TIME
25-Aug-2025 12:29:20 Column TIME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TCAL_SPECTRUM
25-Aug-2025 12:29:20 Column TCAL_SPECTRUM PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TRX_SPECTRUM
25-Aug-2025 12:29:20 Column TRX_SPECTRUM PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TSKY_SPECTRUM
25-Aug-2025 12:29:20 Column TSKY_SPECTRUM PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TSYS_SPECTRUM
25-Aug-2025 12:29:20 Column TSYS_SPECTRUM PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TCAL_FLAG
25-Aug-2025 12:29:20 Column TCAL_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TRX_FLAG
25-Aug-2025 12:29:20 Column TRX_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TSKY_FLAG
25-Aug-2025 12:29:20 Column TSKY_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TSYS_FLAG
25-Aug-2025 12:29:20 Column TSYS_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TANT_FLAG
25-Aug-2025 12:29:20 Column TANT_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TANT_TSYS_FLAG
25-Aug-2025 12:29:20 Column TANT_TSYS_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 *** Subtable WEATHER
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column ANTENNA_ID
25-Aug-2025 12:29:20 Column ANTENNA_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column INTERVAL
25-Aug-2025 12:29:20 Column INTERVAL PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TIME
25-Aug-2025 12:29:20 Column TIME PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column PRESSURE
25-Aug-2025 12:29:20 Column PRESSURE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column PRESSURE_FLAG
25-Aug-2025 12:29:20 Column PRESSURE_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column REL_HUMIDITY
25-Aug-2025 12:29:20 Column REL_HUMIDITY PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column REL_HUMIDITY_FLAG
25-Aug-2025 12:29:20 Column REL_HUMIDITY_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TEMPERATURE
25-Aug-2025 12:29:20 Column TEMPERATURE PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column TEMPERATURE_FLAG
25-Aug-2025 12:29:20 Column TEMPERATURE_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column DEW_POINT
25-Aug-2025 12:29:20 Column DEW_POINT PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column DEW_POINT_FLAG
25-Aug-2025 12:29:20 Column DEW_POINT_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column WIND_DIRECTION
25-Aug-2025 12:29:20 Column WIND_DIRECTION PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column WIND_DIRECTION_FLAG
25-Aug-2025 12:29:20 Column WIND_DIRECTION_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column WIND_SPEED
25-Aug-2025 12:29:20 Column WIND_SPEED PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column WIND_SPEED_FLAG
25-Aug-2025 12:29:20 Column WIND_SPEED_FLAG PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NS_WX_STATION_ID
25-Aug-2025 12:29:20 Column NS_WX_STATION_ID PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 Testing column NS_WX_STATION_POSITION
25-Aug-2025 12:29:20 Column NS_WX_STATION_POSITION PASSED
25-Aug-2025 12:29:20 PASSED
25-Aug-2025 12:29:20
25-Aug-2025 12:29:20 test_tool_sdm_toms.py::asdm_import7::test7_skiprows1
25-Aug-2025 12:29:20 Asdm-import: Test TP asdm, comparing output when duplicate DATA rows are skipped versus not-skipped, lazy and regular, with bdflagging on
25-Aug-2025 12:29:20 SKIPPED (uses --checkdupints option in asdm2MS not available in sdm....)
25-Aug-2025 12:29:20
25-Aug-2025 12:29:21 test_tool_sdm_toms.py::asdm_import8::test_alma_numbin
25-Aug-2025 12:29:21 test_tool_sdm_toms : testing SDM columns in alma_numbin_mixed  writing to  alma_numbin_mixed.ms
25-Aug-2025 12:29:21 test_tool_sdm_toms : testing SDM columns in alma_numbin_mixed  writing to  alma_numbin_mixed.numbin.ms
25-Aug-2025 12:29:21 test_tool_sdm_toms : using SpectralWindow.xml.numBin for SpectralWindow.xml
25-Aug-2025 12:29:21 test_tool_sdm_toms : restored original SpectralWindow.xml
25-Aug-2025 12:29:21 test_tool_sdm_toms : testing SDM columns in alma_numbin_mixed  writing to  alma_numbin_mixed.faked.ms
25-Aug-2025 12:29:21 test_tool_sdm_toms : using SpectralWindow.xml.faked for SpectralWindow.xml
25-Aug-2025 12:29:21 test_tool_sdm_toms : restored original SpectralWindow.xml
25-Aug-2025 12:29:21 test_tool_sdm_toms : testing SDM columns in alma_numbin_mixed  writing to  alma_numbin_mixed.faked.numBin.ms
25-Aug-2025 12:29:21 test_tool_sdm_toms : using SpectralWindow.xml.faked.numBin for SpectralWindow.xml
25-Aug-2025 12:29:21 test_tool_sdm_toms : restored original SpectralWindow.xml
25-Aug-2025 12:29:21 PASSED
25-Aug-2025 12:29:21
25-Aug-2025 12:29:37 test_tool_sdm_toms.py::asdm_import8::test_evla_numbin
25-Aug-2025 12:29:37 test_tool_sdm_toms : testing SDM columns in evla_numbin_2  writing to  evla_numbin_2.ms
25-Aug-2025 12:29:37 test_tool_sdm_toms : testing SDM columns in evla_numbin_2  writing to  evla_numbin_2.numBin.ms
25-Aug-2025 12:29:37 test_tool_sdm_toms : using SpectralWindow.xml.numBin for SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : restored original SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : testing SDM columns in evla_numbin_2  writing to  evla_numbin_2.mixed.ms
25-Aug-2025 12:29:37 test_tool_sdm_toms : using SpectralWindow.xml.mixed for SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : restored original SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : testing SDM columns in evla_numbin_2  writing to  evla_numbin_2.bad.ms
25-Aug-2025 12:29:37 test_tool_sdm_toms : using SpectralWindow.xml.bad for SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : restored original SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : testing SDM columns in evla_numbin_4  writing to  evla_numbin_4.ms
25-Aug-2025 12:29:37 test_tool_sdm_toms : testing SDM columns in evla_numbin_4  writing to  evla_numbin_4.numBin.ms
25-Aug-2025 12:29:37 test_tool_sdm_toms : using SpectralWindow.xml.numBin for SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : restored original SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : testing SDM columns in evla_numbin_4  writing to  evla_numbin_4.onlyNumBin.ms
25-Aug-2025 12:29:37 test_tool_sdm_toms : using SpectralWindow.xml.onlyNumBin for SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : restored original SpectralWindow.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : testing SDM columns in evla_numbin_4  writing to  evla_numbin_4.unknownTel.ms
25-Aug-2025 12:29:37 test_tool_sdm_toms : using ExecBlock.xml.unknownTel for ExecBlock.xml
25-Aug-2025 12:29:37 test_tool_sdm_toms : restored original ExecBlock.xml
25-Aug-2025 12:29:37 PASSED
25-Aug-2025 12:29:37
25-Aug-2025 12:29:37 - generated xml file: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/xml/test_tool_sdm_toms/nose.xml -
25-Aug-2025 12:29:37 =========================== short test summary info ============================
25-Aug-2025 12:29:37 SKIPPED [1] test_tool_sdm_toms.py:1322: uses --inerpolate-ephemeris option in asdm2MS not (yet?) available in sdm.toms
25-Aug-2025 12:29:37 SKIPPED [1] test_tool_sdm_toms.py:1844: uses --checkdupints option in asdm2MS not available in sdm.toms
25-Aug-2025 12:29:37 ============ 12 passed, 2 skipped, 4 warnings in 131.87s (0:02:11) =============
25-Aug-2025 12:29:37 ============================= test session starts ==============================
25-Aug-2025 12:29:37 platform darwin -- Python 3.12.8, pytest-8.4.1, pluggy-1.6.0 -- /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6testenv/bin/python
25-Aug-2025 12:29:37 cachedir: .pytest_cache
25-Aug-2025 12:29:37 rootdir: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_quanta
25-Aug-2025 12:29:37 configfile: pytest.ini
25-Aug-2025 12:29:39 collecting ... collected 9 items
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 2025-08-25 16:29:39        SEVERE                Exception Reported: Error in QuantumHolder::fromString with input string "bogus": Illegal input units or format
25-Aug-2025 12:29:39 2025-08-25 16:29:39        SEVERE        +         in converting quantity
25-Aug-2025 12:29:39 test_tool_quanta.py::quanta_convert_test::test_exceptions
25-Aug-2025 12:29:39 Test various exception cases
25-Aug-2025 12:29:39 PASSED
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 test_tool_quanta.py::quanta_convert_test::test_valid
25-Aug-2025 12:29:39 Test specifying the quantity as a scalar
25-Aug-2025 12:29:39 PASSED
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 test_tool_quanta.py::quanta_convert_test::test_value_as_string
25-Aug-2025 12:29:39 Test specifying value as a string
25-Aug-2025 12:29:39 PASSED
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE                Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE        +         in converting quantity
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE                Exception Reported: Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE        +         in converting quantity
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE                Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE        +         in converting quantity
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE                Exception Reported: Error in QuantumHolder::fromString with input string "blah": Illegal input units or format
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE        +         in converting quantity
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE                Exception Reported: Unit::check Illegal unit string 'zz'
25-Aug-2025 12:29:39 2025-08-25 16:29:40        SEVERE                Exception Reported: Unit::check Illegal unit string 'zz'
25-Aug-2025 12:29:39 test_tool_quanta.py::quanta_quantity_test::test_exceptions
25-Aug-2025 12:29:39 Test various exception cases
25-Aug-2025 12:29:39 PASSED
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 test_tool_quanta.py::quanta_quantity_test::test_multidimensional_arra_value
25-Aug-2025 12:29:39 Test specifying the quantity as a multidimensional array
25-Aug-2025 12:29:39 PASSED
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 test_tool_quanta.py::quanta_quantity_test::test_scalar_value
25-Aug-2025 12:29:39 Test specifying the quantity as a scalar
25-Aug-2025 12:29:39 PASSED
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 test_tool_quanta.py::quanta_quantity_test::test_value_as_record
25-Aug-2025 12:29:39 Test value as record (eg already a quantity).
25-Aug-2025 12:29:39 This is essentially a reflection operator.
25-Aug-2025 12:29:39 PASSED
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 test_tool_quanta.py::quanta_quantity_test::test_value_as_string
25-Aug-2025 12:29:39 Test specifying value as a string
25-Aug-2025 12:29:39 PASSED
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 test_tool_quanta.py::quanta_quantity_test::test_vector_value
25-Aug-2025 12:29:39 Test specifying the quantity as a vector
25-Aug-2025 12:29:39 PASSED
25-Aug-2025 12:29:39
25-Aug-2025 12:29:39 - generated xml file: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/xml/test_tool_quanta/nose.xml -
25-Aug-2025 12:29:39 ======================== 9 passed, 4 warnings in 2.11s =========================
25-Aug-2025 12:29:40 ============================= test session starts ==============================
25-Aug-2025 12:29:40 platform darwin -- Python 3.12.8, pytest-8.4.1, pluggy-1.6.0 -- /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6testenv/bin/python
25-Aug-2025 12:29:40 cachedir: .pytest_cache
25-Aug-2025 12:29:40 rootdir: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_vpmanager
25-Aug-2025 12:29:40 configfile: pytest.ini
25-Aug-2025 12:29:41 collecting ... collected 17 items
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test0
25-Aug-2025 12:29:41 Test 0: reset
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps        Voltage patterns internally defined in CASA (* = global default for this telescope):
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps+          Telescope: Class
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_L1: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_L2: POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        WARN        PBMath::pbMathInterfaceForCommonPB        ATCA_L3 not yet implemented defaulting to L2 version
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_L3: POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_S: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        PBMath1DIPoly::fillPBArray        Using wideband interpolated beam pattern
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_C: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        PBMath1DIPoly::fillPBArray        Using wideband interpolated beam pattern
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_X: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * GBT: POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * GMRT: NUMERIC
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * HATCREEK: GAUSSIAN
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * NRAO12M: NUMERIC
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * UNKNOWN: NUMERIC
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * OVRO: NUMERIC
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA: POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_INVERSE: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_NVSS: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_2NULL: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_4: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_P: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_L: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_C: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_X: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_U: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_K: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * VLA_Q: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * WSRT: COSINE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * WSRT_LOW: COSINE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ALMA: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ALMASD: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ACA: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * IRAMPDB: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * IRAM30M: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * SMA: NUMERIC
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATA: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        PBMath::pbMathInterfaceForCommonPB        PBMath using 2011/10/22 5.5GHz PB
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_C_RI: NUMERIC
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        PBMath1DIPoly::fillPBArray        Using wideband interpolated beam pattern
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_16: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_K: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_Q: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * ATCA_W: INVERSE POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * EVLA: POLYNOMIAL
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps         * NGVLA: AIRY
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps        
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps+        Externally defined voltage patterns (* = global default for this telescope):
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::summarizevps                None
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test1
25-Aug-2025 12:29:41 Test 1: summarizevps
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test10
25-Aug-2025 12:29:41 Test 10: define Airy beams for ALMA antenna types, then use them
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test11
25-Aug-2025 12:29:41 Test 11: getvp without observation parameters
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vp::createantresp        Found 0 response images in directory /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_vpmanager/mydir3
25-Aug-2025 12:29:41 2025-08-25 16:29:41        SEVERE        vp::createantresp        No images found in directory mydir3
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test12
25-Aug-2025 12:29:41 Test 12: EXPECTED ERROR createantresp - no images
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vp::createantresp        Found 6 response images in directory /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_vpmanager/mydir3
25-Aug-2025 12:29:41 2025-08-25 16:29:41        SEVERE        vp::createantresp        Image ALMA_0_DV__0._0._360._0._45._90._80._100._110._GHz_ticra2007_EFP.im has band definition inconsistent with the available bands.
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test13
25-Aug-2025 12:29:41 Test 13: EXPECTED ERROR createantresp - two images have faulty band def
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vp::createantresp        Found 6 response images in directory /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_vpmanager/mydir3
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vp::createantresp        Created antenna responses table /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_vpmanager/mydir3/AntennaResponses
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vp::createantresp+         with 2 rows.
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test14
25-Aug-2025 12:29:41 Test 14: createantresp - good input: six images, two antenna types
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 2025-08-25 16:29:41        SEVERE        vp::getrespimagename        No antenna responses path set for observatory: "ALMA2" in the AntennaResponses column of the Observatories table.
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test15
25-Aug-2025 12:29:41 Test 15: EXPECTED ERROR get image name from non-existant observatory
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        vpmanager::loadfromtable        Loaded 1 VP definitions and 41 VP default settings from table mydefs.tab
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test16
25-Aug-2025 12:29:41 Test 16: define Airy beam for ALMA, save it, load it, then use it
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test2
25-Aug-2025 12:29:41 Test 2: getvp for VLA
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test3
25-Aug-2025 12:29:41 Test 3: getvp and getvps for ALMA
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test4
25-Aug-2025 12:29:41 Test 4: numvps for VLA
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test5
25-Aug-2025 12:29:41 Test 5: numvps for ALMA
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test6
25-Aug-2025 12:29:41 Test 6: numvps for ALMA with too high freq
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test7
25-Aug-2025 12:29:41 Test 7: setuserdefault for ALMA
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test8
25-Aug-2025 12:29:41 Test 8: define Airy beam for ALMA, then use it
25-Aug-2025 12:29:41 PASSED
25-Aug-2025 12:29:41
25-Aug-2025 12:29:41 test_tool_vpmanager.py::vpmanager_test::test9 Processing request for geometries from observatory ALMA, antenna type DV
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        BeamCalc::setBeamCalcGeometries()        Initialisation of geometries for observatory ALMA, antenna type DV
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        BeamCalc::setBeamCalcGeometries()        from file /opt/casa/data/casa-data/alma/responses/ALMA_0_DV__0_0_360_0_45_90_31_506_950_GHz_beamcalc_INTERNAL.txt
25-Aug-2025 12:29:41 2025-08-25 16:29:41        INFO        BeamCalc::setBeamCalcGeometries()+        ... successful.
25-Aug-2025 12:29:41 **Writing ALMA Apertures for Pol 9 to disk
25-Aug-2025 12:29:41 Done writing apertures to disk
25-Aug-2025 12:29:41 Processing request for geometries from observatory ALMA, antenna type DA
25-Aug-2025 12:29:41 2025-08-25 16:29:42        INFO        BeamCalc::setBeamCalcGeometries()        Initialisation of geometries for observatory ALMA, antenna type DA
25-Aug-2025 12:29:41 2025-08-25 16:29:42        INFO        BeamCalc::setBeamCalcGeometries()        from file /opt/casa/data/casa-data/alma/responses/ALMA_0_DA__0_0_360_0_45_90_31_506_950_GHz_beamcalc_INTERNAL.txt
25-Aug-2025 12:29:41 2025-08-25 16:29:42        INFO        BeamCalc::setBeamCalcGeometries()+        ... successful.
25-Aug-2025 12:29:41 **Writing ALMA Apertures for Pol 9 to disk
25-Aug-2025 12:29:41 Done writing apertures to disk
25-Aug-2025 12:29:41 Processing request for geometries from observatory ALMA, antenna type PM
25-Aug-2025 12:29:41 2025-08-25 16:29:42        INFO        BeamCalc::setBeamCalcGeometries()        Initialisation of geometries for observatory ALMA, antenna type PM
25-Aug-2025 12:29:41 2025-08-25 16:29:42        INFO        BeamCalc::setBeamCalcGeometries()        from file /opt/casa/data/casa-data/alma/responses/ALMA_0_PM__0_0_360_0_45_90_31_506_950_GHz_beamcalc_INTERNAL.txt
25-Aug-2025 12:29:41 2025-08-25 16:29:42        INFO        BeamCalc::setBeamCalcGeometries()+        ... successful.
25-Aug-2025 12:29:41 **Writing ALMA Apertures for Pol 9 to disk
25-Aug-2025 12:29:41 Done writing apertures to disk
25-Aug-2025 12:29:42 Processing request for geometries from observatory ALMA, antenna type CM
25-Aug-2025 12:29:42 2025-08-25 16:29:42        INFO        BeamCalc::setBeamCalcGeometries()        Initialisation of geometries for observatory ALMA, antenna type CM
25-Aug-2025 12:29:42 2025-08-25 16:29:42        INFO        BeamCalc::setBeamCalcGeometries()        from file /opt/casa/data/casa-data/alma/responses/ALMA_0_CM__0_0_360_0_45_90_31_506_950_GHz_beamcalc_INTERNAL.txt
25-Aug-2025 12:29:42 2025-08-25 16:29:42        INFO        BeamCalc::setBeamCalcGeometries()+        ... successful.
25-Aug-2025 12:29:42 **Writing ALMA Apertures for Pol 9 to disk
25-Aug-2025 12:29:42 Done writing apertures to disk
25-Aug-2025 12:29:42
25-Aug-2025 12:29:42 Test 9: define reference to antresp table for ALMA, then use it
25-Aug-2025 12:29:42 PASSED
25-Aug-2025 12:29:42
25-Aug-2025 12:29:42 - generated xml file: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/xml/test_tool_vpmanager/nose.xml -
25-Aug-2025 12:29:42 ======================== 17 passed, 4 warnings in 2.21s ========================
25-Aug-2025 12:29:42 ============================= test session starts ==============================
25-Aug-2025 12:29:42 platform darwin -- Python 3.12.8, pytest-8.4.1, pluggy-1.6.0 -- /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6testenv/bin/python
25-Aug-2025 12:29:42 cachedir: .pytest_cache
25-Aug-2025 12:29:42 rootdir: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_simulator
25-Aug-2025 12:29:42 configfile: pytest.ini
25-Aug-2025 12:29:43 collecting ... collected 2 items
25-Aug-2025 12:29:43
25-Aug-2025 12:29:43
25-Aug-2025 12:29:43 test_tool_simulator.py::sm_settrop_test::test_smsettrop
25-Aug-2025 12:29:43 SKIPPED (Skipping due to intermittent failures)
25-Aug-2025 12:29:43
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        NewMSSimulator::initAnt()         Using global coordinates for the antennas
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        NewMSSimulator::initAnt()         Added rows to ANTENNA table
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        MSsimulator::initFeeds()         Added rows to FEED table
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        MSsimulator::initSpWindows()         Creating new spectral window LBand, ID 1
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        simulator::settimes()        Times
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        simulator::settimes()+             Integration time 2000s
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        simulator::settimes()             Times will be interpreted as hour angles for first source
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        NewMSSimulator::observe()        First source: fake @ 19:53:50.00   +40.06.00.00       J2000
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        NewMSSimulator::observe()+        Full time range: 03-Oct-2019/21:16:01.8 -- 04-Oct-2019/07:16:01.8 TAI with int = 2000
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        NewMSSimulator::observe()        Calculating a total of 18 integrations
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Simulator::reset()         Resetting all visibility corruption components
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Simulator::reset()         Reset all image-plane corruption components
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        AgentFlagger::open        Table type is Measurement Set
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: unflag
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: unflag
25-Aug-2025 12:29:43 2025-08-25 16:29:44        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
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (63180/63180)
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        FlagMSHandler::nextChunk         ====================================================================================
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Unflag::tableSummary        => Percentage of data un-flagged in table selection: 100%
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        AgentFlagger::run        => Writing flags to the MS
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        NewMSSimulator::NewMSSimulator(MeasurementSet& theMS)         Opening MeasurementSet /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_simulator/myms.ms with 6318 rows
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        NewMSSimulator::NewMSSimulator(MeasurementSet& theMS)            last spectral window ID = 1
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Simulator::predict()         Predicting visibilities using model: [] and componentList: mycomplist.cl
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Simulator::predict()         The data column will be replaced
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Simulator::getVPRecord         Using default Voltage Patterns from the VPManager
25-Aug-2025 12:29:43 2025-08-25 16:29:44        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.
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Simulator::createSkyEquation()         Synthesis gridding
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Simulator::createSkyEquation()         Fourier transforms will use image centers as tangent points
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Simulator::reset()         Resetting all visibility corruption components
25-Aug-2025 12:29:43 2025-08-25 16:29:44        INFO        Simulator::reset()         Reset all image-plane corruption components
25-Aug-2025 12:29:43 test_tool_simulator.py::sm_predict_test::test_plp
25-Aug-2025 12:29:43 CAS-13439 verify support for plp, spectral curvature model
25-Aug-2025 12:29:43 PASSED
25-Aug-2025 12:29:43
25-Aug-2025 12:29:43 - generated xml file: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/xml/test_tool_simulator/nose.xml -
25-Aug-2025 12:29:43 =========================== short test summary info ============================
25-Aug-2025 12:29:43 SKIPPED [1] test_tool_simulator.py:64: Skipping due to intermittent failures
25-Aug-2025 12:29:43 =================== 1 passed, 1 skipped, 4 warnings in 1.39s ===================
25-Aug-2025 12:29:43 ============================= test session starts ==============================
25-Aug-2025 12:29:43 platform darwin -- Python 3.12.8, pytest-8.4.1, pluggy-1.6.0 -- /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6testenv/bin/python
25-Aug-2025 12:29:43 cachedir: .pytest_cache
25-Aug-2025 12:29:43 rootdir: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_image_transpose
25-Aug-2025 12:29:43 configfile: pytest.ini
25-Aug-2025 12:29:45 collecting ... collected 6 items
25-Aug-2025 12:29:45
25-Aug-2025 12:29:45
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        SubImageFactory::createImage        Creating image 'blahxx.im' of shape [1536, 1536, 1, 2]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        name::        No table opened.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        showcache::        The Table Cache is empty.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        name::        No table opened.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        showcache::        The Table Cache is empty.
25-Aug-2025 12:29:45 test_tool_image_transpose.py::ia_transpose_test::test_cas_2364
25-Aug-2025 12:29:45 test CAS-2364 fix
25-Aug-2025 12:29:45 PASSED
25-Aug-2025 12:29:45
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::open (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 4208)        Exception Reported: Exception: File name is empty.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::open (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 4208)+        ... thrown by static void casa::ImageFactory::_checkInfile(const String &) at File: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DBC47-BPO15AP312/casa6/casatools/src/code/imageanalysis/ImageAnalysis/ImageFactory2.cc, line: 373
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image has 3 axes but 2 were given for reordering. Number of axes to reorder must match the number of image axes
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image has 3 axes but 2 were given for reordering. Number of axes to reorder must match the number of image axes
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Axis number 0 specified multiple times in order parameter 10 . It can only be specified once.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Axis number 0 specified multiple times in order parameter 10 . It can only be specified once.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        CoordinateSystem::getWorldAxesOrder (file ../../coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 2 were given. Number of given axes must match the number of image axes
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        CoordinateSystem::getWorldAxesOrder (file ../../coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 2 were given. Number of given axes must match the number of image axes
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image has 3 axes but 5 were given for reordering. Number of axes to reorder must match the number of image axes
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image has 3 axes but 5 were given for reordering. Number of axes to reorder must match the number of image axes
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        CoordinateSystem::getWorldAxesOrder (file ../../coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 4 were given. Number of given axes must match the number of image axes
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        CoordinateSystem::getWorldAxesOrder (file ../../coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Image has 3 axes but 4 were given. Number of given axes must match the number of image axes
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 123 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        CoordinateSystem::getWorldAxesOrder (file ../../coordinates/Coordinates/CoordinateSystem.cc, line 4665)        No axis matches requested axis s. Image axis names are [right ascension, declination, frequency]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        CoordinateSystem::getWorldAxesOrder (file ../../coordinates/Coordinates/CoordinateSystem.cc, line 4665)        No axis matches requested axis s. Image axis names are [right ascension, declination, frequency]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        CoordinateSystem::getWorldAxesOrder (file ../../coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Ambiguous axis specification. Both r and r match image axis name right ascension
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        CoordinateSystem::getWorldAxesOrder (file ../../coordinates/Coordinates/CoordinateSystem.cc, line 4665)        Ambiguous axis specification. Both r and r match image axis name right ascension
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 103 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        SEVERE        image::transpose (file /private/var/folders/60/83wxljbx2vlfbx_hy0yfv8rc0000gn/T/build-via-sdist-0exa79lv/casatools-6.7.2.32a13324.dev27/src/tools/image/image_cmpt.cc, line 6478)        Exception Reported: 2025-08-25 16:29:45        SEVERE        ImageTransposer::_getOrder        Image does not contain zero-based axis number 3 but this was incorrectly specified in order parameter. 103 All digits in the order parameter must be greater than or equal to zero and less than the number of image axes.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        name::        No table opened.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        showcache::        The Table Cache is empty.
25-Aug-2025 12:29:45 test_tool_image_transpose.py::ia_transpose_test::test_exceptions
25-Aug-2025 12:29:45 imtrans: Test various exception cases
25-Aug-2025 12:29:45 PASSED
25-Aug-2025 12:29:45
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 10, 4, 10] with float valued pixels.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ::::ImageFactory::createImage        Created Temp image  of shape [10, 10, 4, 10] with float valued pixels.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ::::image::fromshape        Ran ia.fromshape
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ::::image::fromshape        ia.fromshape(outfile="", shape=[10,10,4,10], csys={...}, linear=false, overwrite=false, log=true, type="f")
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ::::image::transpose        Ran ia.transpose
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ::::image::transpose        ia.transpose(outfile="", order="3210")
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        name::        No table opened.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        showcache::        The Table Cache is empty.
25-Aug-2025 12:29:45 test_tool_image_transpose.py::ia_transpose_test::test_history
25-Aug-2025 12:29:45 Test history records are written
25-Aug-2025 12:29:45 PASSED
25-Aug-2025 12:29:45
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 20, 30] with float valued pixels.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        BeamManipulator::set        Setting (global) restoring beam.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        BeamManipulator::set        Beam parameters:  Major          : 4 arcmin
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        BeamManipulator::set+          Minor          : 3 arcmin
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        BeamManipulator::set+          Position Angle : 0 deg
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        name::        No table opened.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        showcache::        The Table Cache is empty.
25-Aug-2025 12:29:45 test_tool_image_transpose.py::ia_transpose_test::test_imageinfo
25-Aug-2025 12:29:45 Verify image info is copied
25-Aug-2025 12:29:45 PASSED
25-Aug-2025 12:29:45
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        SubImageFactory::createImage        Creating image 'straight_copy_0' of shape [5, 6, 7]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        SubImageFactory::createImage        Creating image 'straight_copy_1' of shape [5, 6, 7]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        SubImageFactory::createImage        Creating image 'straight_copy_2' of shape [5, 6, 7]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ImageTransposer::transpose        Old to new axis mapping is [0, 1, 2]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        SubImageFactory::createImage        Creating image 'straight_copy_3' of shape [5, 6, 7]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ImageTransposer::transpose        Old to new axis mapping is [0, 1, 2]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        name::        No table opened.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        showcache::        The Table Cache is empty.
25-Aug-2025 12:29:45 test_tool_image_transpose.py::ia_transpose_test::test_straight_copy
25-Aug-2025 12:29:45 No actual transposing
25-Aug-2025 12:29:45 PASSED
25-Aug-2025 12:29:45
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        SubImageFactory::createImage        Creating image 'transpose_0' of shape [6, 7, 5]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        SubImageFactory::createImage        Creating image 'transpose_1' of shape [6, 7, 5]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        SubImageFactory::createImage        Creating image 'transpose_2' of shape [6, 7, 5]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ImageTransposer::transpose        Old to new axis mapping is [1, 2, 0]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ImageTransposer::transpose        Old to new axis mapping is [1, 2, 0]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        SubImageFactory::createImage        Creating image 'transpose_3' of shape [6, 7, 5]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ImageTransposer::transpose        Old to new axis mapping is [1, 2, 0]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        ImageTransposer::transpose        Old to new axis mapping is [1, 2, 0]
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        name::        No table opened.
25-Aug-2025 12:29:45 2025-08-25 16:29:45        INFO        showcache::        The Table Cache is empty.
25-Aug-2025 12:29:45 test_tool_image_transpose.py::ia_transpose_test::test_transpose
25-Aug-2025 12:29:45 Test transposing
25-Aug-2025 12:29:45 PASSED
25-Aug-2025 12:29:45
25-Aug-2025 12:29:45 - generated xml file: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/xml/test_tool_image_transpose/nose.xml -
25-Aug-2025 12:29:45 ======================== 6 passed, 4 warnings in 1.53s =========================
25-Aug-2025 12:29:45 ============================= test session starts ==============================
25-Aug-2025 12:29:45 platform darwin -- Python 3.12.8, pytest-8.4.1, pluggy-1.6.0 -- /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6testenv/bin/python
25-Aug-2025 12:29:45 cachedir: .pytest_cache
25-Aug-2025 12:29:45 rootdir: /Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater
25-Aug-2025 12:29:45 configfile: pytest.ini
25-Aug-2025 12:29:46 collecting ... collected 24 items
25-Aug-2025 12:29:46
25-Aug-2025 12:29:46
25-Aug-2025 12:29:47 2025-08-25 16:29:47        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Aug-2025 12:29:47 2025-08-25 16:29:47        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Aug-2025 12:29:47 2025-08-25 16:29:47        INFO                Clearing all model records in MS header.
25-Aug-2025 12:29:47 2025-08-25 16:29:47        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Aug-2025 12:29:47 2025-08-25 16:29:47        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Aug-2025 12:29:47 2025-08-25 16:29:47        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Aug-2025 12:29:47 2025-08-25 16:29:47        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Aug-2025 12:29:47 2025-08-25 16:29:47        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Aug-2025 12:29:47 2025-08-25 16:29:47        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Aug-2025 12:29:47 2025-08-25 16:29:48        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Aug-2025 12:29:47 2025-08-25 16:29:48        INFO        calibrater::corrupt        Beginning corrupt---------------------------
25-Aug-2025 12:29:47 2025-08-25 16:29:48        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Aug-2025 12:29:47 2025-08-25 16:29:48        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Aug-2025 12:29:48 2025-08-25 16:29:48        INFO        calibrater::corrupt        Finished corrupting.
25-Aug-2025 12:29:48 2025-08-25 16:29:48        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Aug-2025 12:29:48 test_tool_calibrater.py::calibrater_test::test_activityRecord
25-Aug-2025 12:29:48 Check that using the calibrater to modify the vis shows in the activity record 
25-Aug-2025 12:29:48 PASSED
25-Aug-2025 12:29:48
25-Aug-2025 12:29:48 2025-08-25 16:29:48        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Aug-2025 12:29:48 2025-08-25 16:29:48        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Aug-2025 12:29:48 2025-08-25 16:29:48        INFO                Clearing all model records in MS header.
25-Aug-2025 12:29:48 2025-08-25 16:29:48        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Aug-2025 12:29:48 2025-08-25 16:29:49        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Aug-2025 12:29:48 2025-08-25 16:29:49        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Aug-2025 12:29:48 2025-08-25 16:29:49        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Aug-2025 12:29:48 2025-08-25 16:29:49        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Aug-2025 12:29:48 2025-08-25 16:29:49        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Aug-2025 12:29:48 2025-08-25 16:29:49        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Aug-2025 12:29:48 2025-08-25 16:29:49        INFO        calibrater::corrupt        Beginning corrupt---------------------------
25-Aug-2025 12:29:48 2025-08-25 16:29:49        INFO        Calibrater::corrupt2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Aug-2025 12:29:48 2025-08-25 16:29:49        INFO        Calibrater::corrupt2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Aug-2025 12:29:49 2025-08-25 16:29:49        INFO        calibrater::corrupt        Finished corrupting.
25-Aug-2025 12:29:49 2025-08-25 16:29:50        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Aug-2025 12:29:49 test_tool_calibrater.py::calibrater_test::test_corruptCal
25-Aug-2025 12:29:49 Check that the MS is corrupted using the cal table 
25-Aug-2025 12:29:49 PASSED
25-Aug-2025 12:29:49
25-Aug-2025 12:29:49 2025-08-25 16:29:50        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Aug-2025 12:29:49 2025-08-25 16:29:50        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Aug-2025 12:29:49 2025-08-25 16:29:50        INFO                Clearing all model records in MS header.
25-Aug-2025 12:29:49 2025-08-25 16:29:50        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Aug-2025 12:29:50 2025-08-25 16:29:50        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Aug-2025 12:29:50 2025-08-25 16:29:50        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Aug-2025 12:29:50 2025-08-25 16:29:50        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Aug-2025 12:29:50 2025-08-25 16:29:50        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Aug-2025 12:29:50 test_tool_calibrater.py::calibrater_test::test_createEmpty
25-Aug-2025 12:29:50 Check that an empty cal table can be created
25-Aug-2025 12:29:50 PASSED
25-Aug-2025 12:29:50
25-Aug-2025 12:29:50 2025-08-25 16:29:50        INFO        name::        No table opened.
25-Aug-2025 12:29:50 2025-08-25 16:29:50        INFO        showcache::        The Table Cache is empty.
25-Aug-2025 12:29:50 2025-08-25 16:29:50        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Aug-2025 12:29:50 2025-08-25 16:29:50        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Aug-2025 12:29:50 2025-08-25 16:29:50        INFO                Clearing all model records in MS header.
25-Aug-2025 12:29:50 2025-08-25 16:29:50        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        name::        No table opened.
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::        The Table Cache has the following 16 entries:
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            0: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            1: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/ANTENNA"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            2: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/DATA_DESCRIPTION"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            3: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FEED"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            4: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FIELD"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            5: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/FLAG_CMD"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            6: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/HISTORY"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            7: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/OBSERVATION"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            8: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POINTING"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            9: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/POLARIZATION"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            10: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/PROCESSOR"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            11: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SOURCE"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            12: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SPECTRAL_WINDOW"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            13: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/STATE"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            14: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/SYSCAL"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        showcache::            15: "/Users/casaci/bamboohome/xml-data/build-dir/CASASPECS-DEVTESTFULLPY3124-TTAM14P/casa6/casatestutils/nosedir/test_tool_calibrater/gaincaltest2.ms/WEATHER"
25-Aug-2025 12:29:50 2025-08-25 16:29:51        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Aug-2025 12:29:50 test_tool_calibrater.py::calibrater_test::test_done
25-Aug-2025 12:29:50 Check that done closes the active calibrator tool 
25-Aug-2025 12:29:50 PASSED
25-Aug-2025 12:29:50
25-Aug-2025 12:29:50 2025-08-25 16:29:51        WARN        calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO                Clearing all model records in MS header.
25-Aug-2025 12:29:50 2025-08-25 16:29:51        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Aug-2025 12:29:51 2025-08-25 16:29:51        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Aug-2025 12:29:51 2025-08-25 16:29:51        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Aug-2025 12:29:51 2025-08-25 16:29:51        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Aug-2025 12:29:51 2025-08-25 16:29:51        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Aug-2025 12:29:51 2025-08-25 16:29:51        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Aug-2025 12:29:51 2025-08-25 16:29:51        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Aug-2025 12:29:51 2025-08-25 16:29:51        INFO        calibrater::correct        Beginning correct---------------------------
25-Aug-2025 12:29:51 2025-08-25 16:29:51        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Aug-2025 12:29:51 2025-08-25 16:29:51        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Aug-2025 12:29:52 2025-08-25 16:29:52        INFO        calibrater::correct        Finished correcting.
25-Aug-2025 12:29:52 2025-08-25 16:29:52        INFO        calibrater::open        ****Using OLD VI-driven calibrater tool****
25-Aug-2025 12:29:52 2025-08-25 16:29:52        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Aug-2025 12:29:52 2025-08-25 16:29:52        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Aug-2025 12:29:52 2025-08-25 16:29:52        INFO        calibrater::initcalset        Beginning initcalset------------------------
25-Aug-2025 12:29:52 2025-08-25 16:29:52        INFO        VisSet::VisSet        Initializing MODEL_DATA (to unity) and CORRECTED_DATA (to DATA)
25-Aug-2025 12:29:52 2025-08-25 16:29:52        INFO        VisSet::VisSet        Initialized 328860 rows.
25-Aug-2025 12:29:52 2025-08-25 16:29:52        WARN        calibrater::setvi(bool,bool)        Forcing use of NEW VisibilityIterator.
25-Aug-2025 12:29:52 test_tool_calibrater.py::calibrater_test::test_reinitModel
25-Aug-2025 12:29:52 Check that initcalset will reset the CORRECTED_DATA to unity 
25-Aug-2025 12:29:52 PASSED
25-Aug-2025 12:29:52
25-Aug-2025 12:29:52 2025-08-25 16:29:53        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Aug-2025 12:29:52 2025-08-25 16:29:53        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Aug-2025 12:29:52 2025-08-25 16:29:53        INFO                Clearing all model records in MS header.
25-Aug-2025 12:29:52 2025-08-25 16:29:53        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Aug-2025 12:29:52 2025-08-25 16:29:53        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        Calibrater::setsolve        Arranging to SOLVE:
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        Calibrater::setsolve        .   Mf Mueller: table=gaincaltest2.ms.G0 append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        calibrater::reset        Reseting solve/apply state
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        calibrater::solve        Beginning solve-----------------------------
25-Aug-2025 12:29:53 2025-08-25 16:29:53        SEVERE        Calibrater::solve        Caught exception: Please run setsolve before attempting to solve.
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        Calibrater::solve        Reseting entire solve/apply state.
25-Aug-2025 12:29:53 2025-08-25 16:29:53        SEVERE                Exception Reported: Error in Calibrater::solve.
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        calibrater::correct        Beginning correct---------------------------
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        Calibrater::correct2 (VI2/VB2)        .   (None)
25-Aug-2025 12:29:53 2025-08-25 16:29:53        SEVERE        Calibrater::correct2 (VI2/VB2)        Caught exception: Nothing to Apply
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        Calibrater::correct2 (VI2/VB2)        Resetting all calibration application settings.
25-Aug-2025 12:29:53 2025-08-25 16:29:53        SEVERE                Exception Reported: Error in Calibrater::correct.
25-Aug-2025 12:29:53 2025-08-25 16:29:53        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Aug-2025 12:29:53 test_tool_calibrater.py::calibrater_test::test_resetSolveApply
25-Aug-2025 12:29:53 Check that the reset function can clear set apply and solves 
25-Aug-2025 12:29:53 PASSED
25-Aug-2025 12:29:53
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO                Clearing all model records in MS header.
25-Aug-2025 12:29:53 2025-08-25 16:29:53        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        calibrater::reset        Reseting solve/apply state
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        Calibrater::selectvis+         Selecting on spw: '1'
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 82215
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        Calibrater::selectvis        Frequency selection:
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        Calibrater::selectvis        .  Spw 1:0~7 (8 channels, step by 1)
25-Aug-2025 12:29:53 2025-08-25 16:29:54        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
25-Aug-2025 12:29:53 2025-08-25 16:29:54        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Aug-2025 12:29:54 test_tool_calibrater.py::calibrater_test::test_returnDict
25-Aug-2025 12:29:54 Check that the returndict function gives a dictonary with the expected keys 
25-Aug-2025 12:29:54 PASSED
25-Aug-2025 12:29:54
25-Aug-2025 12:29:54 2025-08-25 16:29:54        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
25-Aug-2025 12:29:54 2025-08-25 16:29:54        INFO        calibrater::open        Opening MS: gaincaltest2.ms for calibration.
25-Aug-2025 12:29:54 2025-08-25 16:29:54        INFO                Clearing all model records in MS header.
25-Aug-2025 12:29:54 2025-08-25 16:29:54        INFO        VisSetUtil::addScrCols        Adding MODEL_DATA and CORRECTED_DATA column(s).
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        VisSetUtil::initScrCols        Initializing MODEL_DATA to (unity).
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        VisSetUtil::initScrCols        Initialized 328860 rows.
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        Calibrater::        Initializing nominal selection to the whole MS.
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        calibrater::reset        Reseting solve/apply state
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        Calibrater::selectvis+         Selecting on antenna/baseline: '0'
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        Calibrater::selectvis        By selection 328860 rows are reduced to 65772
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        calibrater::correct        Beginning correct---------------------------
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
25-Aug-2025 12:29:54 2025-08-25 16:29:55        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=gaincaltest2.ms.G0 select= interp=linear spwmap=[-1] calWt=false
25-Aug-2025 12:29:55 2025-08-25 16:29:55        INFO        calibrater::correct        Finished correcting.
25-Aug-2025 12:29:55 2025-08-25 16:29:55        WARN        calibrater::setvi(bool,bool)        Already using NEW VisibilityIterator.
25-Aug-2025 12:29:55 test_tool_calibrater.py::calibrater_test::test_selectVisBaseline
25-Aug-2025 12:29:55 Check that selectvis properly selects baseline/antenna
25-Aug-2025 12:29:55 65772 65772
25-Aug-2025 12:29:55 263088 263088
25-Aug-2025 12:29:55 PASSED
25-Aug-2025 12:29:55
25-Aug-2025 12:29:55 test_tool_calibrater.py::calibrater_test::test_selectVisField
25-Aug-2025 12:29:55 Failing task since return code of [/Users/casaci/bamboohome/temp/CASASPECS-DEVTESTFULLPY3124-TTAM14P-4-ScriptBuildTask-17477917937630686465.sh] was 143 while expected 0
25-Aug-2025 12:29:55 Finished task 'Test wheel' with result: Failed
25-Aug-2025 12:29:55 Running post build plugin 'Docker Container Cleanup'
25-Aug-2025 12:29:55 Running post build plugin 'NCover Results Collector'
25-Aug-2025 12:29:55 Running post build plugin 'Build Results Label Collector'
25-Aug-2025 12:29:55 Running post build plugin 'Clover Results Collector'
25-Aug-2025 12:29:55 Running post build plugin 'npm Cache Cleanup'
25-Aug-2025 12:29:55 Running post build plugin 'Artifact Copier'
25-Aug-2025 12:29:55 Publishing an artifact: requirements.txt
25-Aug-2025 12:29:55 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
25-Aug-2025 12:29:55 Attempting to generate stack trace and terminate spawned sub-processes of process id: 48287
25-Aug-2025 12:29:55 Found related process: pid: 10143 ppid: 1 pgid: 2356 %cpu: 0.0 %mem: 0.2 cmd: /opt/local/Library/Frameworks/Python.framework/Versions/3.12/Resources/Python.app/Contents/MacOS/Python
25-Aug-2025 12:29:55 getStackTraceAndKillRelatedProcesses for 1 processes
25-Aug-2025 12:29:55 Executing kill -3 10143
25-Aug-2025 12:29:55 Finished publishing of artifact Non required job artifact Http Compression On : [requirements.txt], patterns: [requirements.txt] anchored at: [wheeldirectory/] in 136.0 ms
25-Aug-2025 12:29:55 Publishing an artifact: Workdir
25-Aug-2025 12:29:55 Failing as no matching files has been found and empty artifacts are not allowed.
25-Aug-2025 12:29:55 Unable to publish artifact [Workdir]:
25-Aug-2025 12:29:55 The artifact hasn't been successfully published after 31.39 ms
25-Aug-2025 12:29:55 Finalising the build...
25-Aug-2025 12:29:55 Stopping timer.
25-Aug-2025 12:29:55 Build CASASPECS-DEVTESTFULLPY3124-TTAM14P-4 completed.
25-Aug-2025 12:29:55 Running on server: post build plugin 'Build Hanging Detection Configuration'
25-Aug-2025 12:29:55 Running on server: post build plugin 'NCover Results Collector'
25-Aug-2025 12:29:55 Running on server: post build plugin 'Build Labeller'
25-Aug-2025 12:29:55 Running on server: post build plugin 'Clover Delta Calculator'
25-Aug-2025 12:29:55 Running on server: post build plugin 'Maven Dependencies Postprocessor'
25-Aug-2025 12:29:55 All post build plugins have finished
25-Aug-2025 12:29:55 Generating build results summary...
25-Aug-2025 12:29:55 Saving build results to disk...
25-Aug-2025 12:29:55 Store variable context...
25-Aug-2025 12:29:55 Finished building CASASPECS-DEVTESTFULLPY3124-TTAM14P-4.
25-Aug-2025 12:30:00 Killing: 10143
25-Aug-2025 12:30:00 Executing kill 10143
25-Aug-2025 12:30:05 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
25-Aug-2025 12:30:05 Has finished generating stack trace and terminating spawned sub-processes of process id: 48287