Build log

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

13-Mar-2026 00:54:26	2026-03-13 04:54:25 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:26	2026-03-13 04:54:25 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:26	.2026-03-13 04:54:26 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa mstransform( vis='Four_ants_3C286.ms', outputvis='testmms3.mms', createmms=True, separationaxis='scan', numsubms='auto', tileshape=[0], field='', spw='0:0~10,1:60~63', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/testmms3.mms.data/testmms3.mms.0000.ms', createmms=False, separationaxis='scan', numsubms='auto', tileshape=[0], field='', spw='0:0~10,1:60~63', scan='30', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms3.mms.data/testmms3.mms.0000.ms
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseDataSelParams scan selection is 30
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseDataSelParams spw selection is 0:0~10,1:60~63
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::initDataSelectionParams Selected Scans Ids are [30]
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::initDataSelectionParams+ [0, 0, 10, 1
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformDataHandler::makeSelection 1068 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:25.803586 End time: 2026-03-13 04:54:26.472177
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/testmms3.mms.data/testmms3.mms.0001.ms', createmms=False, separationaxis='scan', numsubms='auto', tileshape=[0], field='', spw='0:0~10,1:60~63', scan='31', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms3.mms.data/testmms3.mms.0001.ms
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseDataSelParams scan selection is 31
13-Mar-2026 00:54:26	2026-03-13 04:54:26 INFO MSTransformManager::parseDataSelParams spw selection is 0:0~10,1:60~63
13-Mar-2026 00:54:26	2026-03-13 04:54:27 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
13-Mar-2026 00:54:26	2026-03-13 04:54:27 INFO MSTransformManager::initDataSelectionParams Selected Scans Ids are [31]
13-Mar-2026 00:54:26	2026-03-13 04:54:27 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO MSTransformManager::initDataSelectionParams+ [0, 0, 10, 1
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO MSTransformDataHandler::makeSelection 1080 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:26.490235 End time: 2026-03-13 04:54:27.112813
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/testmms3.mms.data/testmms3.mms.0000.ms to the other MMS members.
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:25.628033 End time: 2026-03-13 04:54:27.394768
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:29	2026-03-13 04:54:27 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:29	.2026-03-13 04:54:28 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO mstransform::::casa mstransform( vis='Four_ants_3C286.ms', outputvis='testmms4.mms', createmms=True, separationaxis='spw', numsubms='auto', tileshape=[0], field='', spw='3,5:10~20,7,11,13', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0000.ms', createmms=False, separationaxis='spw', numsubms='auto', tileshape=[0], field='', spw='3', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=0, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0000.ms
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::parseDataSelParams spw selection is 3
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::initDataSelectionParams+ [3, 0, 63, 1]
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:29	2026-03-13 04:54:28 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:27.954282 End time: 2026-03-13 04:54:28.589058
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0001.ms', createmms=False, separationaxis='spw', numsubms='auto', tileshape=[0], field='', spw='5:10~20', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0001.ms
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::parseDataSelParams spw selection is 5:10~20
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::parseFreqTransParams DDI start is 1
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::initDataSelectionParams+ [5, 10, 20, 1]
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:29	2026-03-13 04:54:29 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:28.607109 End time: 2026-03-13 04:54:29.207974
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0002.ms', createmms=False, separationaxis='spw', numsubms='auto', tileshape=[0], field='', spw='7', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=2, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0002.ms
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::parseDataSelParams spw selection is 7
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::parseFreqTransParams DDI start is 2
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::initDataSelectionParams+ [7, 0, 63, 1]
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:30	2026-03-13 04:54:29 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:29.225617 End time: 2026-03-13 04:54:29.861328
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0003.ms', createmms=False, separationaxis='spw', numsubms='auto', tileshape=[0], field='', spw='11', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=3, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0003.ms
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::parseDataSelParams spw selection is 11
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::parseFreqTransParams DDI start is 3
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::initDataSelectionParams+ [11, 0, 63, 1]
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:30	2026-03-13 04:54:30 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:29.879013 End time: 2026-03-13 04:54:30.518663
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0004.ms', createmms=False, separationaxis='spw', numsubms='auto', tileshape=[0], field='', spw='13', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=4, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0004.ms
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:30	2026-03-13 04:54:31 INFO MSTransformManager::parseDataSelParams spw selection is 13
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformManager::parseFreqTransParams DDI start is 4
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformManager::initDataSelectionParams+ [13, 0, 63, 1]
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:30.536708 End time: 2026-03-13 04:54:31.167609
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO ParallelDataHelper::::casa Consolidate the sub-tables
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformDataHandler::mergeSpwSubTables Merging SPECTRAL_WINDOW sub-tables from all sub-MSs to form MMS-level SPECTRAL_WINDOW sub-table
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformDataHandler::mergeDDISubTables Merging DDI sub-tables from all sub-MSs to form MMS-level DDI sub-table
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformDataHandler::mergeFeedSubTables Merging FEED sub-tables from all sub-MSs to form MMS-level FEED sub-table
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformDataHandler::mergeSourceSubTables Merging SOURCE sub-tables from all sub-MSs to form MMS-level SOURCE sub-table
13-Mar-2026 00:54:40	2026-03-13 04:54:31 INFO MSTransformDataHandler::mergeSyscalSubTables Merging SYSCAL sub-tables from all sub-MSs to form MMS-level SYSCAL sub-table
13-Mar-2026 00:54:40	2026-03-13 04:54:32 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:40	2026-03-13 04:54:32 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0000.ms to the other MMS members.
13-Mar-2026 00:54:40	2026-03-13 04:54:32 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:27.771141 End time: 2026-03-13 04:54:32.429849
13-Mar-2026 00:54:40	2026-03-13 04:54:32 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:40	2026-03-13 04:54:32 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:40	.2026-03-13 04:54:33 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:40	2026-03-13 04:54:33 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:40	2026-03-13 04:54:33 INFO mstransform::::casa mstransform( vis='jupiter6cm.demo-thinned.ms', outputvis='combspw13.mms', createmms=True, separationaxis='scan', numsubms=6, tileshape=[0], field='12', spw='0,1', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='DATA', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=False, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:40	2026-03-13 04:54:33 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:40	2026-03-13 04:54:36 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:40	2026-03-13 04:54:36 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/combspw13.mms.data/combspw13.mms.0000.ms to the other MMS members.
13-Mar-2026 00:54:40	2026-03-13 04:54:37 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:33.110456 End time: 2026-03-13 04:54:37.183798
13-Mar-2026 00:54:40	2026-03-13 04:54:37 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:40	2026-03-13 04:54:37 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:40	.2026-03-13 04:54:38 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:40	2026-03-13 04:54:38 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:40	2026-03-13 04:54:38 INFO mstransform::::casa mstransform( vis='Four_ants_3C286.ms', outputvis='inpmms14.mms', createmms=True, separationaxis='auto', numsubms='auto', tileshape=[0], field='', spw='0,1', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=False, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:40	2026-03-13 04:54:38 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:40	2026-03-13 04:54:38 INFO ParallelDataHelper::::casa Automatically distribute the scan/spw pairs to balance the load along field, spw and scan axes
13-Mar-2026 00:54:40	2026-03-13 04:54:38 INFO msmetadata_cmpt.cc::open Performing internal consistency checks on /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms...
13-Mar-2026 00:54:40	2026-03-13 04:54:39 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:40	2026-03-13 04:54:39 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/inpmms14.mms.data/inpmms14.mms.0000.ms to the other MMS members.
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:37.705330 End time: 2026-03-13 04:54:39.688713
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO mstransform::::casa mstransform( vis='inpmms14.mms', outputvis='combspw14.ms', createmms=False, separationaxis='auto', numsubms='auto', tileshape=[0], field='', spw='0:60~63,1:60~63', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='data', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=True, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:40	2026-03-13 04:54:40 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/inpmms14.mms/SUBMSS/inpmms14.mms.0000.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0000.ms', createmms=False, separationaxis='auto', numsubms='auto', tileshape=[0], field='', spw='0:60~63,1:60~63', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='data', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=True, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO mstransform::::casa Combine spws 0:60~63,1:60~63 into new output spw
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/inpmms14.mms/SUBMSS/inpmms14.mms.0000.ms
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::parseMsSpecParams Data column is DATA
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0000.ms
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::parseDataSelParams spw selection is 0:60~63,1:60~63
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::parseFreqTransParams Combine Spectral Windows is activated
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input DATA column
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::initDataSelectionParams+ [0, 60, 63, 1
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:41	2026-03-13 04:54:40 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::regridAndCombineSpwSubtable Calculate combined SPW frequencies
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::combineSpwsCore Input SPWs sorted by first (lowest) channel frequency:
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::combineSpwsCore SPW 0: 4 channels, first channel = 1.696000000e+09 Hz, last channel = 1.699000000e+09 Hz
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::combineSpwsCore SPW 1: 4 channels, first channel = 1.760000000e+09 Hz, last channel = 1.763000000e+09 Hz
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::regridSpwAux Combined SPW: 8 channels, first channel = 1.696000000e+09 Hz, last channel = 1.763000000e+09 Hz, first width = 1.000000000e+06 Hz, last width = 1.000000000e+06 Hz
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::regridSpwAux Calculate frequencies in output reference frame
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs Channels equidistant in freq
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Central frequency (in output frame) = 1.7295e+09 Hz
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Width of central channel (in output frame) = 1e+06 Hz
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Number of channels = 68
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Total width of SPW (in output frame) = 6.8e+07 Hz
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Lower edge = 1.6955e+09 Hz, upper edge = 1.7635e+09 Hz
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::regridSpwAux Output SPW: 68 channels, first channel = 1.696000000e+09 Hz, last channel = 1.763000000e+09 Hz, first width = 1.000000000e+06 Hz, last width = 1.000000000e+06 Hz
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::regridAndCombineSpwSubtable Write output SPW subtable
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::reindexDDISubTable Re-indexing DDI sub-table
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::reindexSourceSubTable Re-indexing SOURCE sub-table
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::reindexGenericTimeDependentSubTable Re-indexing SPW column of FEED sub-table and removing duplicates
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::setIterationApproach Combining data from selected spectral windows
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:40.133227 End time: 2026-03-13 04:54:41.009523
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/inpmms14.mms/SUBMSS/inpmms14.mms.0001.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0001.ms', createmms=False, separationaxis='auto', numsubms='auto', tileshape=[0], field='', spw='0:60~63,1:60~63', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='data', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=True, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO mstransform::::casa Combine spws 0:60~63,1:60~63 into new output spw
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/inpmms14.mms/SUBMSS/inpmms14.mms.0001.ms
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::parseMsSpecParams Data column is DATA
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0001.ms
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::parseDataSelParams spw selection is 0:60~63,1:60~63
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::parseFreqTransParams Combine Spectral Windows is activated
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input DATA column
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::initDataSelectionParams+ [0, 60, 63, 1
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformManager::regridAndCombineSpwSubtable Calculate combined SPW frequencies
13-Mar-2026 00:54:41	2026-03-13 04:54:41 INFO MSTransformRegridder::combineSpwsCore Input SPWs sorted by first (lowest) channel frequency:
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformRegridder::combineSpwsCore SPW 0: 4 channels, first channel = 1.696000000e+09 Hz, last channel = 1.699000000e+09 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformRegridder::combineSpwsCore SPW 1: 4 channels, first channel = 1.760000000e+09 Hz, last channel = 1.763000000e+09 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformManager::regridSpwAux Combined SPW: 8 channels, first channel = 1.696000000e+09 Hz, last channel = 1.763000000e+09 Hz, first width = 1.000000000e+06 Hz, last width = 1.000000000e+06 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformManager::regridSpwAux Calculate frequencies in output reference frame
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs Channels equidistant in freq
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Central frequency (in output frame) = 1.7295e+09 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Width of central channel (in output frame) = 1e+06 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Number of channels = 68
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Total width of SPW (in output frame) = 6.8e+07 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformRegridder::calcChanFreqs+ Lower edge = 1.6955e+09 Hz, upper edge = 1.7635e+09 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformManager::regridSpwAux Output SPW: 68 channels, first channel = 1.696000000e+09 Hz, last channel = 1.763000000e+09 Hz, first width = 1.000000000e+06 Hz, last width = 1.000000000e+06 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformManager::regridAndCombineSpwSubtable Write output SPW subtable
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformManager::reindexDDISubTable Re-indexing DDI sub-table
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformManager::reindexSourceSubTable Re-indexing SOURCE sub-table
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformManager::reindexGenericTimeDependentSubTable Re-indexing SPW column of FEED sub-table and removing duplicates
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO MSTransformManager::setIterationApproach Combining data from selected spectral windows
13-Mar-2026 00:54:42	2026-03-13 04:54:41 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:41.027502 End time: 2026-03-13 04:54:41.725545
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/inpmms14.mms/SUBMSS/inpmms14.mms.0002.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0002.ms', createmms=False, separationaxis='auto', numsubms='auto', tileshape=[0], field='', spw='0:60~63,1:60~63', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='data', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=True, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa Combine spws 0:60~63,1:60~63 into new output spw
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/inpmms14.mms/SUBMSS/inpmms14.mms.0002.ms
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::parseMsSpecParams Data column is DATA
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0002.ms
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::parseDataSelParams spw selection is 0:60~63,1:60~63
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::parseFreqTransParams Combine Spectral Windows is activated
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input DATA column
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::initDataSelectionParams+ [0, 60, 63, 1
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::regridAndCombineSpwSubtable Calculate combined SPW frequencies
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformRegridder::combineSpwsCore Input SPWs sorted by first (lowest) channel frequency:
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformRegridder::combineSpwsCore SPW 0: 4 channels, first channel = 1.696000000e+09 Hz, last channel = 1.699000000e+09 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformRegridder::combineSpwsCore SPW 1: 4 channels, first channel = 1.760000000e+09 Hz, last channel = 1.763000000e+09 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::regridSpwAux Combined SPW: 8 channels, first channel = 1.696000000e+09 Hz, last channel = 1.763000000e+09 Hz, first width = 1.000000000e+06 Hz, last width = 1.000000000e+06 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::regridSpwAux Calculate frequencies in output reference frame
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformRegridder::calcChanFreqs Channels equidistant in freq
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformRegridder::calcChanFreqs+ Central frequency (in output frame) = 1.7295e+09 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformRegridder::calcChanFreqs+ Width of central channel (in output frame) = 1e+06 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformRegridder::calcChanFreqs+ Number of channels = 68
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformRegridder::calcChanFreqs+ Total width of SPW (in output frame) = 6.8e+07 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformRegridder::calcChanFreqs+ Lower edge = 1.6955e+09 Hz, upper edge = 1.7635e+09 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::regridSpwAux Output SPW: 68 channels, first channel = 1.696000000e+09 Hz, last channel = 1.763000000e+09 Hz, first width = 1.000000000e+06 Hz, last width = 1.000000000e+06 Hz
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::regridAndCombineSpwSubtable Write output SPW subtable
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::reindexDDISubTable Re-indexing DDI sub-table
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::reindexSourceSubTable Re-indexing SOURCE sub-table
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::reindexGenericTimeDependentSubTable Re-indexing SPW column of FEED sub-table and removing duplicates
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO MSTransformManager::setIterationApproach Combining data from selected spectral windows
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:41.743086 End time: 2026-03-13 04:54:42.451831
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:42	2026-03-13 04:54:42 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0000.ms to the other MMS members.
13-Mar-2026 00:54:45	2026-03-13 04:54:43 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:39.705903 End time: 2026-03-13 04:54:42.946004
13-Mar-2026 00:54:45	2026-03-13 04:54:43 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:45	2026-03-13 04:54:43 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:45	2026-03-13 04:54:43 INFO cvel::::casa ##########################################
13-Mar-2026 00:54:45	2026-03-13 04:54:43 INFO cvel::::casa ##### Begin Task: cvel #####
13-Mar-2026 00:54:45	2026-03-13 04:54:43 INFO cvel::::casa cvel( vis='Four_ants_3C286.ms', outputvis='combcvel14.ms', passall=False, field='', spw='0:60~63,1:60~63', selectdata=True, antenna='', timerange='', scan='', array='', mode='channel', nchan=-1, start=0, width=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', hanning=False )
13-Mar-2026 00:54:45	2026-03-13 04:54:43 INFO cvel::::casa Creating selected SubMS ...
13-Mar-2026 00:54:45	2026-03-13 04:54:43 INFO SubMS::parseColumnNames() Using DATA MODEL_DATA CORRECTED_DATA columns.
13-Mar-2026 00:54:45	2026-03-13 04:54:43 INFO SubMS::makeSelection() 2148 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::doChannelMods() Data binned.
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO cvel::::casa Using 3C286_A (original field 0, new field 0) as common direction for the output reference frame.
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO ms::cvel Input spectral window 0 has 4 channels of width 1.000000e+06 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO ms::cvel+ First channel center = 1.696000000e+09 Hz, last channel center = 1.699000000e+09 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO ms::cvel Input spectral window 1 has 4 channels of width 1.000000e+06 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO ms::cvel+ First channel center = 1.760000000e+09 Hz, last channel center = 1.763000000e+09 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO ms::cvel Starting combination of spectral windows ...
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::combineSpws() Input SPWs sorted by first (lowest) channel frequency:
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::combineSpws() SPW 0: 4 channels, first channel = 1.696000000e+09 Hz, last channel = 1.699000000e+09 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::combineSpws() SPW 1: 4 channels, first channel = 1.760000000e+09 Hz, last channel = 1.763000000e+09 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::combineSpws() Combined SPW will have 8 channels. May change in later regridding.
13-Mar-2026 00:54:45	combineSpws progress: 20% processed ...
13-Mar-2026 00:54:45	combineSpws progress: 40% processed ...
13-Mar-2026 00:54:45	combineSpws progress: 60% processed ...
13-Mar-2026 00:54:45	combineSpws progress: 80% processed ...
13-Mar-2026 00:54:45	combineSpws progress: 100% processed.
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::combineSpws() Processed 2148 original rows, wrote 1074 new ones.
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::combineSpws() Spectral window combination complete.
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO ms::cvel
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO ms::cvel Testing if spectral frame transformation/regridding is needed ...
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw() Using tabulated observatory position for EVLA:
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw() Position: [-1.60116e+06, -5.04199e+06, 3.55488e+06] (ITRF)
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw() Regridded spectral window 0 will be created for field 1 with parameters
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ input frame = TOPO, output frame = TOPO
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ Channels equidistant in freq
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ Central frequency (in output frame) = 1.7295e+09 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ Width of central channel (in output frame) = 1e+06 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ Number of channels = 68
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ Total width of SPW (in output frame) = 6.8e+07 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ Lower edge = 1.6955e+09 Hz, upper edge = 1.7635e+09 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ Interpolation Method = linear
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw() Added 1 new rows to the DATA_DESCRIPTION table and deleted 1 old ones.
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ Added 1 rows to the SPECTRAL_WINDOW table and deleted 1 old ones.
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw()+ Added 1 rows to the SOURCE table and deleted 2 old ones.
13-Mar-2026 00:54:45	2026-03-13 04:54:44 INFO SubMS::regridSpw() Main table data array columns will be rewritten.
13-Mar-2026 00:54:45	regridSpw progress: 20% processed ...
13-Mar-2026 00:54:45	regridSpw progress: 40% processed ...
13-Mar-2026 00:54:45	regridSpw progress: 60% processed ...
13-Mar-2026 00:54:45	regridSpw progress: 80% processed ...
13-Mar-2026 00:54:45	regridSpw progress: 100% processed.
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO ms::cvel Spectral frame transformation/regridding completed.
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO ms::cvel Final spectral window has 68 channels of width 1.000000e+06 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO ms::cvel+ First channel center = 1.696000000e+09 Hz, last channel center = 1.763000000e+09 Hz
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO ms::cvel NOTE: any virtual model data will be cleared.
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO Clearing all model records in MS header.
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO cvel::::casa Task cvel complete. Start time: 2026-03-13 04:54:43.015553 End time: 2026-03-13 04:54:44.670613
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO cvel::::casa ##### End Task: cvel #####
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO cvel::::casa ##########################################
13-Mar-2026 00:54:45	.2026-03-13 04:54:45 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO mstransform::::casa mstransform( vis='Four_ants_3C286.ms', outputvis='testmms13.mms', createmms=True, separationaxis='scan', numsubms='auto', tileshape=[0], field='', spw='', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/testmms13.mms.data/testmms13.mms.0000.ms', createmms=False, separationaxis='scan', numsubms='auto', tileshape=[0], field='', spw='', scan='30', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms13.mms.data/testmms13.mms.0000.ms
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:45	2026-03-13 04:54:45 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:50	2026-03-13 04:54:45 INFO MSTransformManager::parseDataSelParams scan selection is 30
13-Mar-2026 00:54:50	2026-03-13 04:54:45 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
13-Mar-2026 00:54:50	2026-03-13 04:54:45 INFO MSTransformManager::initDataSelectionParams Selected Scans Ids are [30]
13-Mar-2026 00:54:50	2026-03-13 04:54:45 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:50	2026-03-13 04:54:45 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:50	2026-03-13 04:54:45 INFO MSTransformDataHandler::makeSelection 8544 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:50	2026-03-13 04:54:46 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:45.245190 End time: 2026-03-13 04:54:46.816743
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO mstransform::::casa mstransform( vis='/home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms', outputvis='/home/casatest/work/test_task_mstransform_mms/testmms13.mms.data/testmms13.mms.0001.ms', createmms=False, separationaxis='scan', numsubms='auto', tileshape=[0], field='', spw='', scan='31', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='corrected', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=True, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms13.mms.data/testmms13.mms.0001.ms
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::parseDataSelParams scan selection is 31
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::initDataSelectionParams Selected Scans Ids are [31]
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::open Select data
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO MSTransformDataHandler::makeSelection 8640 out of 17184 rows are going to be considered due to the selection criteria.
13-Mar-2026 00:54:50	2026-03-13 04:54:47 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:46.823580 End time: 2026-03-13 04:54:48.304939
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/testmms13.mms.data/testmms13.mms.0000.ms to the other MMS members.
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:45.051681 End time: 2026-03-13 04:54:48.418840
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO mstransform::::casa mstransform( vis='testmms13.mms', outputvis='reg13.ms', createmms=False, separationaxis='auto', numsubms='auto', tileshape=[0], field='', spw='1,3,5,7', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='DATA', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=True, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=False, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:50	2026-03-13 04:54:48 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
13-Mar-2026 00:54:50	2026-03-13 04:54:50 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:50	2026-03-13 04:54:50 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/reg13.ms.data/reg13.ms.0000.ms to the other MMS members.
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:48.424990 End time: 2026-03-13 04:54:50.580465
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::::casa ##########################################
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::::casa ##### Begin Task: listobs #####
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::::casa listobs( vis='reg13.ms', selectdata=True, spw='', field='', antenna='', uvrange='', timerange='', correlation='', scan='', intent='', feed='', array='', observation='', verbose=True, listfile='', listunfl=False, cachesize=50.0, overwrite=False )
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary ================================================================================
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary+ MeasurementSet Name: /home/casatest/work/test_task_mstransform_mms/reg13.ms MS Version 2
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary+ ================================================================================
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary+ Observer: Ms. Urvashi Rao Venkata Project: T.B.D.
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary+ Observation: EVLA
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::MSMetaData::_computeScanAndSubScanProperties Computing scan and subscan properties...
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary Data records: 4296 Total elapsed time = 179.8 seconds
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary+ Observed from 16-Oct-2010/14:45:08.0 to 16-Oct-2010/14:48:07.8 (UTC)
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary+ ObservationID = 0 ArrayID = 0
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary+ Date Timerange (UTC) Scan FldId FieldName nRows SpwIds Average Interval(s) ScanIntent
13-Mar-2026 00:54:50	2026-03-13 04:54:51 INFO listobs::ms::summary+ 16-Oct-2010/14:45:08.0 - 14:46:37.0 30 1 3C286_A 2136 [0,1,2,3] [1, 1, 1, 1] [CALIBRATE_AMPLI.UNSPECIFIED,CALIBRATE_BANDPASS.UNSPECIFIED,CALIBRATE_PHASE.UNSPECIFIED,OBSERVE_TARGET.UNSPECIFIED]
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 14:46:37.8 - 14:48:07.8 31 1 3C286_A 2160 [0,1,2,3] [1, 1, 1, 1] [CALIBRATE_AMPLI.UNSPECIFIED,CALIBRATE_BANDPASS.UNSPECIFIED,CALIBRATE_PHASE.UNSPECIFIED,OBSERVE_TARGET.UNSPECIFIED]
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary (nRows = Total number of rows per scan)
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary Fields: 1
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ ID Code Name RA Decl Epoch SrcId nRows
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 1 Q 3C286_A 13:31:08.287985 +30.30.32.95886 J2000 1 4296
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary Spectral Windows: (4 unique spectral windows and 1 unique polarization setups)
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ SpwID Name #Chans Frame Ch0(MHz) ChanWid(kHz) TotBW(kHz) CtrFreq(MHz) Corrs
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 0 Subband:1 64 TOPO 1700.000 1000.000 64000.0 1731.5000 RR RL LR LL
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 1 Subband:3 64 TOPO 1828.000 1000.000 64000.0 1859.5000 RR RL LR LL
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 2 Subband:5 64 TOPO 1956.000 1000.000 64000.0 1987.5000 RR RL LR LL
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 3 Subband:7 64 TOPO 2084.000 1000.000 64000.0 2115.5000 RR RL LR LL
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary Sources: 8
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ ID Name SpwId RestFreq(MHz) SysVel(km/s)
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 0 3C286_A 0 - -
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 0 3C286_A 1 - -
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 0 3C286_A 2 - -
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 0 3C286_A 3 - -
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 1 3C286_A 0 - -
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 1 3C286_A 1 - -
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 1 3C286_A 2 - -
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 1 3C286_A 3 - -
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary Antennas: 4:
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ ID Name Station Diam. Long. Lat. Offset from array center (m) ITRF Geocentric coordinates (m)
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ East North Elevation x y z
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 0 ea01 W18 25.0 m -107.38.08.9 +33.53.26.5 -1643.9556 -1089.8808 2.3920 -1602908.188700 -5042072.300200 3553975.909500
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 1 ea11 E04 25.0 m -107.37.00.8 +33.53.59.7 102.8054 -63.7682 -2.6414 -1601068.790300 -5042051.910200 3554824.835300
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 2 ea19 W04 25.0 m -107.37.10.8 +33.53.59.1 -152.8599 -83.8054 -2.4614 -1601315.893000 -5041985.320170 3554808.304600
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::ms::summary+ 3 ea24 W16 25.0 m -107.37.57.4 +33.53.33.0 -1348.7111 -890.6282 1.3030 -1602592.855600 -5042055.002200 3554140.700700
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::::casa Task listobs complete. Start time: 2026-03-13 04:54:50.625710 End time: 2026-03-13 04:54:50.698514
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::::casa ##### End Task: listobs #####
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO listobs::::casa ##########################################
13-Mar-2026 00:54:54	.s2026-03-13 04:54:51 INFO partition::::casa ##########################################
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO partition::::casa ##### Begin Task: partition #####
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO partition::::casa partition( vis='test-subtables-alma.ms', outputvis='test-subtables-alma.mms', createmms=True, separationaxis='auto', numsubms='auto', flagbackup=True, datacolumn='all', field='', spw='', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', disableparallel=False, ddistart=-1, taql='' )
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO ParallelDataHelper::::casa Automatically distribute the scan/spw pairs to balance the load along field, spw and scan axes
13-Mar-2026 00:54:54	2026-03-13 04:54:51 INFO msmetadata_cmpt.cc::open Performing internal consistency checks on /home/casatest/work/test_task_mstransform_mms/test-subtables-alma.ms...
13-Mar-2026 00:54:54	2026-03-13 04:54:53 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:54	2026-03-13 04:54:53 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/test-subtables-alma.mms.data/test-subtables-alma.mms.0000.ms to the other MMS members.
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO partition::::casa Create a backup of the flags that are in the MMS
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO AgentFlagger::open Table type is Measurement Set
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO FlagVersion::saveFlagVersion Creating new backup flag file called partition_1
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO partition::::casa Task partition complete. Start time: 2026-03-13 04:54:51.002511 End time: 2026-03-13 04:54:54.121901
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO partition::::casa ##### End Task: partition #####
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO partition::::casa ##########################################
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO mstransform::::casa mstransform( vis='test-subtables-alma.mms', outputvis='test_no_reindexing_noreidnex.mms', createmms=False, separationaxis='auto', numsubms='auto', tileshape=[0], field='SXDF-NB1006-4', spw='1:10~20,2:30~40', scan='', antenna='', correlation='XX', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='ALL', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=True, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='lsrk', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=False, ddistart=-1, taql='', monolithic_processing=False, reindex=False )
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:54	2026-03-13 04:54:54 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
13-Mar-2026 00:54:54	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa Command request with id 115 failed in server n# 3 with traceback Traceback (most recent call last):
13-Mar-2026 00:54:54	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.4-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casampi/private/MPICommandServer.py", line 208, in __command_request_handler_service
13-Mar-2026 00:54:54	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ command_response['ret'] = eval(eval_str)
13-Mar-2026 00:54:54	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ ^^^^^^^^^^^^^^
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "<string>", line 1, in <module>
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.4-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casatasks/mstransform.py", line 620, in __call__
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ task_result = _mstransform_t( _pc.document['vis'], _pc.document['outputvis'], _pc.document['createmms'], _pc.document['separationaxis'], _pc.document['numsubms'], _pc.document['tileshape'], _pc.document['field'], _pc.document['spw'], _pc.document['scan'], _pc.document['antenna'], _pc.document['correlation'], _pc.document['timerange'], _pc.document['intent'], _pc.document['array'], _pc.document['uvrange'], _pc.document['observation'], _pc.document['feed'], _pc.document['datacolumn'], _pc.document['realmodelcol'], _pc.document['keepflags'], _pc.document['usewtspectrum'], _pc.document['combinespws'], _pc.document['chanaverage'], _pc.document['chanbin'], _pc.document['hanning'], _pc.document['regridms'], _pc.document['mode'], _pc.document['nchan'], _pc.document['start'], _pc.document['width'], _pc.document['nspw'], _pc.document['interpolation'], _pc.document['phasecenter'], _pc.document['restfreq'], _pc.document['outframe'], _pc.document['veltype'], _pc.document['preaverage'], _pc.document['timeaverage'], _pc.document['timebin'], _pc.document['timespan'], _pc.document['maxuvwdistance'], _pc.document['docallib'], _pc.document['callib'], _pc.document['douvcontsub'], _pc.document['fitspw'], _pc.document['fitorder'], _pc.document['want_cont'], _pc.document['denoising_lib'], _pc.document['nthreads'], _pc.document['niter'], _pc.document['disableparallel'], _pc.document['ddistart'], _pc.document['taql'], _pc.document['monolithic_processing'], _pc.document['reindex'] )
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.4-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casatasks/private/task_mstransform.py", line 293, in mstransform
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ mtlocal.open()
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.4-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casatools/mstransformer.py", line 36, in open
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ return self._swigobj.open()
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ ^^^^^^^^^^^^^^^^^^^^
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.4-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casatools/__casac__/mstransformer.py", line 176, in open
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ return _mstransformer.mstransformer_open(self)
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
13-Mar-2026 00:54:59	2026-03-13 04:54:55 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ RuntimeError: MSSelectionNullSelection : The selected table has zero rows.
13-Mar-2026 00:54:59	2026-03-13 04:54:56 INFO ParallelDataHelper::::casa 1 subMSs failed to be created. This is not an error, if due to selection when creating a Multi-MS
13-Mar-2026 00:54:59	2026-03-13 04:54:56 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:59	2026-03-13 04:54:56 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/test_no_reindexing_noreidnex.mms.data/test_no_reindexing_noreidnex.mms.0000.ms to the other MMS members.
13-Mar-2026 00:54:59	2026-03-13 04:54:57 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:54.138682 End time: 2026-03-13 04:54:56.736831
13-Mar-2026 00:54:59	2026-03-13 04:54:57 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:54:59	2026-03-13 04:54:57 INFO mstransform::::casa ##########################################
13-Mar-2026 00:54:59	.2026-03-13 04:54:57 INFO partition::::casa ##########################################
13-Mar-2026 00:54:59	2026-03-13 04:54:57 INFO partition::::casa ##### Begin Task: partition #####
13-Mar-2026 00:54:59	2026-03-13 04:54:57 INFO partition::::casa partition( vis='mstransform_reference/ngc5921.ms', outputvis='ngc5921.mms', createmms=True, separationaxis='auto', numsubms='auto', flagbackup=True, datacolumn='all', field='', spw='', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', disableparallel=False, ddistart=-1, taql='' )
13-Mar-2026 00:54:59	2026-03-13 04:54:57 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:54:59	2026-03-13 04:54:58 INFO ParallelDataHelper::::casa Automatically distribute the scan/spw pairs to balance the load along field, spw and scan axes
13-Mar-2026 00:54:59	2026-03-13 04:54:58 INFO msmetadata_cmpt.cc::open Performing internal consistency checks on /home/casatest/work/test_task_mstransform_mms/mstransform_reference/ngc5921.ms...
13-Mar-2026 00:54:59	2026-03-13 04:54:59 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:54:59	2026-03-13 04:54:59 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/ngc5921.mms.data/ngc5921.mms.0000.ms to the other MMS members.
13-Mar-2026 00:54:59	2026-03-13 04:54:59 INFO partition::::casa Create a backup of the flags that are in the MMS
13-Mar-2026 00:54:59	2026-03-13 04:55:00 INFO AgentFlagger::open Table type is Measurement Set
13-Mar-2026 00:54:59	2026-03-13 04:55:00 INFO FlagVersion::saveFlagVersion Creating new backup flag file called partition_1
13-Mar-2026 00:54:59	2026-03-13 04:55:00 INFO partition::::casa Task partition complete. Start time: 2026-03-13 04:54:57.491276 End time: 2026-03-13 04:54:59.659774
13-Mar-2026 00:54:59	2026-03-13 04:55:00 INFO partition::::casa ##### End Task: partition #####
13-Mar-2026 00:55:02	2026-03-13 04:55:00 INFO partition::::casa ##########################################
13-Mar-2026 00:55:02	2026-03-13 04:55:00 INFO mstransform::::casa ##########################################
13-Mar-2026 00:55:02	2026-03-13 04:55:00 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:55:02	2026-03-13 04:55:00 INFO mstransform::::casa mstransform( vis='ngc5921.mms', outputvis='mst_otf_calibration.mms', createmms=True, separationaxis='auto', numsubms='auto', tileshape=[0], field='', spw='', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='all', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=True, callib='mstransform_reference/ngc5921_callib.txt', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=False, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:55:02	2026-03-13 04:55:00 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:55:02	2026-03-13 04:55:00 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
13-Mar-2026 00:55:02	2026-03-13 04:55:00 SEVERE Calibrater::::MPIServer-3 Caltable mstransform_reference/ngc5921.bcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:00 SEVERE Calibrater::::MPIServer-3 Caltable mstransform_reference/ngc5921.fluxscale is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:00 SEVERE Calibrater::::MPIServer-3 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:00 SEVERE Calibrater::::MPIServer-3 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:01 SEVERE Calibrater::::MPIServer-1 Caltable mstransform_reference/ngc5921.bcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:01 SEVERE Calibrater::::MPIServer-1 Caltable mstransform_reference/ngc5921.fluxscale is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:01 SEVERE Calibrater::::MPIServer-1 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:01 SEVERE Calibrater::::MPIServer-1 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:01 SEVERE Calibrater::::MPIServer-2 Caltable mstransform_reference/ngc5921.bcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:01 SEVERE Calibrater::::MPIServer-2 Caltable mstransform_reference/ngc5921.fluxscale is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:01 SEVERE Calibrater::::MPIServer-2 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:01 SEVERE Calibrater::::MPIServer-2 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN . The following callib entry matches no data
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + . in the selected MS (ngc5921.mms.0000.ms) and will be ignored:
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + . 0:
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + MS: obs= scan= fld=0 intent= spw=
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + CT: tinterp=nearest finterp=
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + obsmap=[] scanmap=[] fldmap=[0]
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + spwmap=[] antmap=[]
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN . The following callib entry matches no data
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + . in the selected MS (ngc5921.mms.0001.ms) and will be ignored:
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + . 0:
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + MS: obs= scan= fld=0 intent= spw=
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + CT: tinterp=nearest finterp=
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + obsmap=[] scanmap=[] fldmap=[0]
13-Mar-2026 00:55:02	2026-03-13 04:55:01 WARN + spwmap=[] antmap=[]
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/mst_otf_calibration.mms.data/mst_otf_calibration.mms.0000.ms to the other MMS members.
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:54:59.676854 End time: 2026-03-13 04:55:02.356791
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO mstransform::::casa ##########################################
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO mstransform::::casa ##########################################
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO mstransform::::casa mstransform( vis='mstransform_reference/ngc5921.ms', outputvis='mst_otf_calibration.ms', createmms=False, separationaxis='auto', numsubms='auto', tileshape=[0], field='', spw='', scan='', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='all', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=True, callib='mstransform_reference/ngc5921_callib.txt', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=False, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO mstransform::::casa Parse docallib parameters
13-Mar-2026 00:55:02	/home/casatest/work/pkg/casa-6.7.4-1-py3.12.el8/lib/py/lib/python3.12/site-packages/casatasks/private/task_mstransform.py:268: ResourceWarning: unclosed file <_io.TextIOWrapper name='mstransform_reference/ngc5921_callib.txt' mode='r' encoding='UTF-8'>
13-Mar-2026 00:55:02	mycallib.read(callib)
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::parseMsSpecParams Input file name is mstransform_reference/ngc5921.ms
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::parseMsSpecParams Data column is ALL
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::parseMsSpecParams Output file name is mst_otf_calibration.ms
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::parseCalParams Calibration is activated
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input DATA column
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::colCheckInfo Adding CORRECTED_DATA column to output MS from input virtual CORRECTED_DATA column
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::open Select data
13-Mar-2026 00:55:02	2026-03-13 04:55:02 INFO MSTransformManager::createOutputMSStructure Create output MS structure
13-Mar-2026 00:55:02	2026-03-13 04:55:03 INFO MSTransformManager::checkFillWeightSpectrum Optional column WEIGHT_SPECTRUM found in input MS will be written to output MS
13-Mar-2026 00:55:02	2026-03-13 04:55:03 INFO MSTransformManager::generateIterator OTF calibration activated, using calibration record spec to generate iterator
13-Mar-2026 00:55:02	2026-03-13 04:55:03 INFO Calibrater:: Arranging to calibrate MS: /home/casatest/work/test_task_mstransform_mms/mstransform_reference/ngc5921.ms
13-Mar-2026 00:55:02	2026-03-13 04:55:03 SEVERE Calibrater:: Caltable mstransform_reference/ngc5921.bcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:03 SEVERE Calibrater:: Caltable mstransform_reference/ngc5921.fluxscale is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:03 SEVERE Calibrater:: Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:03 SEVERE Calibrater:: Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
13-Mar-2026 00:55:02	2026-03-13 04:55:03 INFO OldCalibrater::setcallib2(callib) Arranging to APPLY:
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO . B Jones: table=mstransform_reference/ngc5921.bcal (by cal library) calWt=true
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO . 0:
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + MS: obs= scan= fld= intent= spw=
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + CT: tinterp=nearest finterp=
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + obsmap=[] scanmap=[] fldmap=[]
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + spwmap=[] antmap=[]
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO OldCalibrater::setcallib2(callib) Arranging to APPLY:
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO . G Jones: table=mstransform_reference/ngc5921.fluxscale (by cal library) calWt=true
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO . 0:
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + MS: obs= scan= fld= intent= spw=
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + CT: tinterp=nearest finterp=
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + obsmap=[] scanmap=[] fldmap=[0, 1, 1]
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + spwmap=[] antmap=[]
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO OldCalibrater::setcallib2(callib) Arranging to APPLY:
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO . G Jones: table=mstransform_reference/ngc5921.gcal (by cal library) calWt=true
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO . 0:
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + MS: obs= scan= fld=0 intent= spw=
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + CT: tinterp=nearest finterp=
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + obsmap=[] scanmap=[] fldmap=[0]
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + spwmap=[] antmap=[]
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO . 1:
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + MS: obs= scan= fld=1,2 intent= spw=
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + CT: tinterp=linear finterp=
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + obsmap=[] scanmap=[] fldmap=[1, 1, 1]
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO + spwmap=[] antmap=[]
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO OldCalibrater::setcallib2(callib) The following calibration terms are arranged for apply:
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO OldCalibrater::setcallib2(callib) . B Jones: table=mstransform_reference/ngc5921.bcal (by cal library) calWt=true
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO OldCalibrater::setcallib2(callib) . G Jones: table=mstransform_reference/ngc5921.fluxscale (by cal library) calWt=true
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO OldCalibrater::setcallib2(callib) . G Jones: table=mstransform_reference/ngc5921.gcal (by cal library) calWt=true
13-Mar-2026 00:55:27	2026-03-13 04:55:03 INFO mstransform::::casa Apply the transformations
13-Mar-2026 00:55:27	2026-03-13 04:55:04 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:55:02.373538 End time: 2026-03-13 04:55:04.276444
13-Mar-2026 00:55:27	2026-03-13 04:55:04 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:55:27	2026-03-13 04:55:04 INFO mstransform::::casa ##########################################
13-Mar-2026 00:55:27	2026-03-13 04:55:15 INFO ms::sort Sorted main table of /home/casatest/work/test_task_mstransform_mms/mst_otf_calibration.mms by [OBSERVATION_ID,ARRAY_ID,SCAN_NUMBER,FIELD_ID,DATA_DESC_ID,ANTENNA1,ANTENNA2,TIME] and stored it in mst_otf_calibration_sorted.mms .
13-Mar-2026 00:55:27	2026-03-13 04:55:26 INFO ms::sort Sorted main table of /home/casatest/work/test_task_mstransform_mms/mst_otf_calibration.ms by [OBSERVATION_ID,ARRAY_ID,SCAN_NUMBER,FIELD_ID,DATA_DESC_ID,ANTENNA1,ANTENNA2,TIME] and stored it in mst_otf_calibration_sorted.ms .
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column OFFSET
13-Mar-2026 00:55:27	Column OFFSET PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column POSITION
13-Mar-2026 00:55:27	Column POSITION PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TYPE
13-Mar-2026 00:55:27	Column TYPE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column DISH_DIAMETER
13-Mar-2026 00:55:27	Column DISH_DIAMETER PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FLAG_ROW
13-Mar-2026 00:55:27	Column FLAG_ROW PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column MOUNT
13-Mar-2026 00:55:27	Column MOUNT PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column NAME
13-Mar-2026 00:55:27	Column NAME PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column STATION
13-Mar-2026 00:55:27	Column STATION PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FLAG_ROW
13-Mar-2026 00:55:27	Column FLAG_ROW PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column POLARIZATION_ID
13-Mar-2026 00:55:27	Column POLARIZATION_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column SPECTRAL_WINDOW_ID
13-Mar-2026 00:55:27	Column SPECTRAL_WINDOW_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column POSITION
13-Mar-2026 00:55:27	Column POSITION PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column BEAM_OFFSET
13-Mar-2026 00:55:27	Column BEAM_OFFSET PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column POLARIZATION_TYPE
13-Mar-2026 00:55:27	Column POLARIZATION_TYPE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column POL_RESPONSE
13-Mar-2026 00:55:27	Column POL_RESPONSE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column RECEPTOR_ANGLE
13-Mar-2026 00:55:27	Column RECEPTOR_ANGLE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column ANTENNA_ID
13-Mar-2026 00:55:27	Column ANTENNA_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column BEAM_ID
13-Mar-2026 00:55:27	Column BEAM_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FEED_ID
13-Mar-2026 00:55:27	Column FEED_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column INTERVAL
13-Mar-2026 00:55:27	Column INTERVAL PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column NUM_RECEPTORS
13-Mar-2026 00:55:27	Column NUM_RECEPTORS PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column SPECTRAL_WINDOW_ID
13-Mar-2026 00:55:27	Column SPECTRAL_WINDOW_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TIME
13-Mar-2026 00:55:27	Column TIME PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column DELAY_DIR
13-Mar-2026 00:55:27	Column DELAY_DIR PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column PHASE_DIR
13-Mar-2026 00:55:27	Column PHASE_DIR PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column REFERENCE_DIR
13-Mar-2026 00:55:27	Column REFERENCE_DIR PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column CODE
13-Mar-2026 00:55:27	Column CODE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FLAG_ROW
13-Mar-2026 00:55:27	Column FLAG_ROW PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column NAME
13-Mar-2026 00:55:27	Column NAME PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column NUM_POLY
13-Mar-2026 00:55:27	Column NUM_POLY PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column SOURCE_ID
13-Mar-2026 00:55:27	Column SOURCE_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TIME
13-Mar-2026 00:55:27	Column TIME PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column APPLIED
13-Mar-2026 00:55:27	Column APPLIED PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column COMMAND
13-Mar-2026 00:55:27	Column COMMAND PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column INTERVAL
13-Mar-2026 00:55:27	Column INTERVAL PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column LEVEL
13-Mar-2026 00:55:27	Column LEVEL PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column REASON
13-Mar-2026 00:55:27	Column REASON PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column SEVERITY
13-Mar-2026 00:55:27	Column SEVERITY PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TIME
13-Mar-2026 00:55:27	Column TIME PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TYPE
13-Mar-2026 00:55:27	Column TYPE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column DIRECTION
13-Mar-2026 00:55:27	Column DIRECTION PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column ANTENNA_ID
13-Mar-2026 00:55:27	Column ANTENNA_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column INTERVAL
13-Mar-2026 00:55:27	Column INTERVAL PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column NAME
13-Mar-2026 00:55:27	Column NAME PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column NUM_POLY
13-Mar-2026 00:55:27	Column NUM_POLY PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TARGET
13-Mar-2026 00:55:27	Column TARGET PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TIME
13-Mar-2026 00:55:27	Column TIME PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TIME_ORIGIN
13-Mar-2026 00:55:27	Column TIME_ORIGIN PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TRACKING
13-Mar-2026 00:55:27	Column TRACKING PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column CORR_TYPE
13-Mar-2026 00:55:27	Column CORR_TYPE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column CORR_PRODUCT
13-Mar-2026 00:55:27	Column CORR_PRODUCT PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FLAG_ROW
13-Mar-2026 00:55:27	Column FLAG_ROW PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column NUM_CORR
13-Mar-2026 00:55:27	Column NUM_CORR PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FLAG_ROW
13-Mar-2026 00:55:27	Column FLAG_ROW PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column MODE_ID
13-Mar-2026 00:55:27	Column MODE_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TYPE
13-Mar-2026 00:55:27	Column TYPE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TYPE_ID
13-Mar-2026 00:55:27	Column TYPE_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column SUB_TYPE
13-Mar-2026 00:55:27	Column SUB_TYPE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column CAL
13-Mar-2026 00:55:27	Column CAL PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FLAG_ROW
13-Mar-2026 00:55:27	Column FLAG_ROW PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column LOAD
13-Mar-2026 00:55:27	Column LOAD PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column OBS_MODE
13-Mar-2026 00:55:27	Column OBS_MODE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column REF
13-Mar-2026 00:55:27	Column REF PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column SIG
13-Mar-2026 00:55:27	Column SIG PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column SUB_SCAN
13-Mar-2026 00:55:27	Column SUB_SCAN PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TIME_RANGE
13-Mar-2026 00:55:27	Column TIME_RANGE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FLAG_ROW
13-Mar-2026 00:55:27	Column FLAG_ROW PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column OBSERVER
13-Mar-2026 00:55:27	Column OBSERVER PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column PROJECT
13-Mar-2026 00:55:27	Column PROJECT PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column RELEASE_DATE
13-Mar-2026 00:55:27	Column RELEASE_DATE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column SCHEDULE_TYPE
13-Mar-2026 00:55:27	Column SCHEDULE_TYPE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TELESCOPE_NAME
13-Mar-2026 00:55:27	Column TELESCOPE_NAME PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column UVW
13-Mar-2026 00:55:27	Column UVW PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FLAG
13-Mar-2026 00:55:27	Column FLAG PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column WEIGHT
13-Mar-2026 00:55:27	Column WEIGHT PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column ANTENNA1
13-Mar-2026 00:55:27	Column ANTENNA1 PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column ANTENNA2
13-Mar-2026 00:55:27	Column ANTENNA2 PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column ARRAY_ID
13-Mar-2026 00:55:27	Column ARRAY_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column DATA_DESC_ID
13-Mar-2026 00:55:27	Column DATA_DESC_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column EXPOSURE
13-Mar-2026 00:55:27	Column EXPOSURE PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FEED1
13-Mar-2026 00:55:27	Column FEED1 PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FEED2
13-Mar-2026 00:55:27	Column FEED2 PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FIELD_ID
13-Mar-2026 00:55:27	Column FIELD_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column FLAG_ROW
13-Mar-2026 00:55:27	Column FLAG_ROW PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column INTERVAL
13-Mar-2026 00:55:27	Column INTERVAL PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column OBSERVATION_ID
13-Mar-2026 00:55:27	Column OBSERVATION_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column PROCESSOR_ID
13-Mar-2026 00:55:27	Column PROCESSOR_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column SCAN_NUMBER
13-Mar-2026 00:55:27	Column SCAN_NUMBER PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column STATE_ID
13-Mar-2026 00:55:27	Column STATE_ID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TIME
13-Mar-2026 00:55:27	Column TIME PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column TIME_CENTROID
13-Mar-2026 00:55:27	Column TIME_CENTROID PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column DATA
13-Mar-2026 00:55:27	Column DATA PASSED
13-Mar-2026 00:55:27
13-Mar-2026 00:55:27	Testing column CORRECTED_DATA
13-Mar-2026 00:55:27	Column CORRECTED_DATA PASSED
13-Mar-2026 00:55:27	.2026-03-13 04:55:28 INFO mstransform::::casa ##########################################
13-Mar-2026 00:55:27	2026-03-13 04:55:28 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:55:38	2026-03-13 04:55:28 INFO mstransform::::casa mstransform( vis='CAS-6733.ms', outputvis='test_vla_mixed_polarizations_1.mms', createmms=True, separationaxis='spw', numsubms='auto', tileshape=[0], field='', spw='16~18', scan='16', antenna='', correlation='', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='DATA', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=False, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:55:38	2026-03-13 04:55:28 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:55:38	2026-03-13 04:55:32 INFO ParallelDataHelper::::casa Consolidate the sub-tables
13-Mar-2026 00:55:38	2026-03-13 04:55:32 INFO MSTransformDataHandler::mergeSpwSubTables Merging SPECTRAL_WINDOW sub-tables from all sub-MSs to form MMS-level SPECTRAL_WINDOW sub-table
13-Mar-2026 00:55:38	2026-03-13 04:55:32 INFO MSTransformDataHandler::mergeDDISubTables Merging DDI sub-tables from all sub-MSs to form MMS-level DDI sub-table
13-Mar-2026 00:55:38	2026-03-13 04:55:32 INFO MSTransformDataHandler::mergeFeedSubTables Merging FEED sub-tables from all sub-MSs to form MMS-level FEED sub-table
13-Mar-2026 00:55:38	2026-03-13 04:55:32 INFO MSTransformDataHandler::mergeSourceSubTables Merging SOURCE sub-tables from all sub-MSs to form MMS-level SOURCE sub-table
13-Mar-2026 00:55:38	2026-03-13 04:55:32 INFO MSTransformDataHandler::mergeCalDeviceSubtables Merging CALDEVICE sub-tables from all sub-MSs to form MMS-level CALDEVICE sub-table
13-Mar-2026 00:55:38	2026-03-13 04:55:32 INFO MSTransformDataHandler::mergeSysPowerSubtables Merging SYSPOWER sub-tables from all sub-MSs to form MMS-level SYSPOWER sub-table
13-Mar-2026 00:55:38	2026-03-13 04:55:33 INFO MSTransformDataHandler::mergeSyscalSubTables Merging SYSCAL sub-tables from all sub-MSs to form MMS-level SYSCAL sub-table
13-Mar-2026 00:55:38	2026-03-13 04:55:33 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:55:38	2026-03-13 04:55:33 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/test_vla_mixed_polarizations_1.mms.data/test_vla_mixed_polarizations_1.mms.0000.ms to the other MMS members.
13-Mar-2026 00:55:38	2026-03-13 04:55:33 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:55:27.505471 End time: 2026-03-13 04:55:32.962608
13-Mar-2026 00:55:38	2026-03-13 04:55:33 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:55:38	2026-03-13 04:55:33 INFO mstransform::::casa ##########################################
13-Mar-2026 00:55:38	2026-03-13 04:55:33 INFO flagdata::::casa ##########################################
13-Mar-2026 00:55:38	2026-03-13 04:55:33 INFO flagdata::::casa ##### Begin Task: flagdata #####
13-Mar-2026 00:55:38	2026-03-13 04:55:33 INFO flagdata::::casa flagdata( vis='test_vla_mixed_polarizations_1.mms', mode='summary', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='', field='', antenna='', uvrange='', timerange='', correlation='', scan='', intent='', array='', observation='', feed='', clipminmax=[], datacolumn='DATA', clipoutside=True, channelavg=False, chanbin=1, timeavg=False, timebin='0s', clipzeros=False, quackinterval=1.0, quackmode='beg', quackincrement=False, tolerance=0.0, addantenna='', lowerlimit=0.0, upperlimit=90.0, ntime='scan', combinescans=False, timecutoff=4.0, freqcutoff=3.0, timefit='line', freqfit='poly', maxnpieces=7, flagdimension='freqtime', usewindowstats='none', halfwin=1, extendflags=True, winsize=3, timedev='', freqdev='', timedevscale=5.0, freqdevscale=5.0, spectralmax=1000000.0, spectralmin=0.0, antint_ref_antenna='', minchanfrac=0.6, verbose=False, extendpols=True, growtime=50.0, growfreq=50.0, growaround=False, flagneartime=False, flagnearfreq=False, minrel=0.0, maxrel=1.0, minabs=0, maxabs=-1, spwchan=False, spwcorr=False, basecnt=False, fieldcnt=False, name='Summary', action='apply', display='', flagbackup=True, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
13-Mar-2026 00:55:38	2026-03-13 04:55:34 WARN ParallelTaskHelper::consolidateResults::casa ***** consolidateResults, subMS: {'antenna': {'ea01': {'flagged': 0.0, 'total': 512.0}, 'ea02': {'flagged': 0.0, 'total': 512.0}}, 'array': {'0': {'flagged': 0.0, 'total': 512.0}}, 'correlation': {'XX': {'flagged': 0.0, 'total': 128.0}, 'XY': {'flagged': 0.0, 'total': 128.0}, 'YX': {'flagged': 0.0, 'total': 128.0}, 'YY': {'flagged': 0.0, 'total': 128.0}}, 'field': {'J1911+1611': {'flagged': 0.0, 'total': 512.0}}, 'flagged': 0.0, 'name': 'Summary', 'observation': {'0': {'flagged': 0.0, 'total': 512.0}}, 'scan': {'16': {'flagged': 0.0, 'total': 512.0}}, 'spw': {'0': {'flagged': 0.0, 'total': 512.0}}, 'total': 512.0, 'type': 'summary'}
13-Mar-2026 00:55:38	2026-03-13 04:55:34 WARN ParallelTaskHelper::consolidateResults::casa ***** consolidateResults, subMS: {'antenna': {'ea01': {'flagged': 0.0, 'total': 512.0}, 'ea02': {'flagged': 0.0, 'total': 512.0}}, 'array': {'0': {'flagged': 0.0, 'total': 512.0}}, 'correlation': {'XX': {'flagged': 0.0, 'total': 128.0}, 'XY': {'flagged': 0.0, 'total': 128.0}, 'YX': {'flagged': 0.0, 'total': 128.0}, 'YY': {'flagged': 0.0, 'total': 128.0}}, 'field': {'J1911+1611': {'flagged': 0.0, 'total': 512.0}}, 'flagged': 0.0, 'name': 'Summary', 'observation': {'0': {'flagged': 0.0, 'total': 512.0}}, 'scan': {'16': {'flagged': 0.0, 'total': 512.0}}, 'spw': {'1': {'flagged': 0.0, 'total': 512.0}}, 'total': 512.0, 'type': 'summary'}
13-Mar-2026 00:55:38	2026-03-13 04:55:34 WARN ParallelTaskHelper::consolidateResults::casa ***** consolidateResults, subMS: {'antenna': {'ea01': {'flagged': 0.0, 'total': 512.0}, 'ea02': {'flagged': 0.0, 'total': 512.0}}, 'array': {'0': {'flagged': 0.0, 'total': 512.0}}, 'correlation': {'XX': {'flagged': 0.0, 'total': 128.0}, 'XY': {'flagged': 0.0, 'total': 128.0}, 'YX': {'flagged': 0.0, 'total': 128.0}, 'YY': {'flagged': 0.0, 'total': 128.0}}, 'field': {'J1911+1611': {'flagged': 0.0, 'total': 512.0}}, 'flagged': 0.0, 'name': 'Summary', 'observation': {'0': {'flagged': 0.0, 'total': 512.0}}, 'scan': {'16': {'flagged': 0.0, 'total': 512.0}}, 'spw': {'2': {'flagged': 0.0, 'total': 512.0}}, 'total': 512.0, 'type': 'summary'}
13-Mar-2026 00:55:38	2026-03-13 04:55:34 INFO flagdata::::casa Task flagdata complete. Start time: 2026-03-13 04:55:32.974496 End time: 2026-03-13 04:55:33.714748
13-Mar-2026 00:55:38	2026-03-13 04:55:34 INFO flagdata::::casa ##### End Task: flagdata #####
13-Mar-2026 00:55:38	2026-03-13 04:55:34 INFO flagdata::::casa ##########################################
13-Mar-2026 00:55:38	.2026-03-13 04:55:34 INFO mstransform::::casa ##########################################
13-Mar-2026 00:55:38	2026-03-13 04:55:34 INFO mstransform::::casa ##### Begin Task: mstransform #####
13-Mar-2026 00:55:38	2026-03-13 04:55:34 INFO mstransform::::casa mstransform( vis='CAS-6733.ms', outputvis='test_vla_mixed_polarizations_2.mms', createmms=True, separationaxis='spw', numsubms='auto', tileshape=[0], field='', spw='16~18', scan='16', antenna='', correlation='XX', timerange='', intent='', array='', uvrange='', observation='', feed='', datacolumn='DATA', realmodelcol=False, keepflags=True, usewtspectrum=False, combinespws=False, chanaverage=False, chanbin=1, hanning=False, regridms=False, mode='channel', nchan=-1, start=0, width=1, nspw=1, interpolation='linear', phasecenter='', restfreq='', outframe='', veltype='radio', preaverage=False, timeaverage=False, timebin='0s', timespan='', maxuvwdistance=0.0, docallib=False, callib='', douvcontsub=False, fitspw='', fitorder=0, want_cont=False, denoising_lib=True, nthreads=1, niter=1, disableparallel=False, ddistart=-1, taql='', monolithic_processing=False, reindex=True )
13-Mar-2026 00:55:38	2026-03-13 04:55:34 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
13-Mar-2026 00:55:38	2026-03-13 04:55:39 INFO ParallelDataHelper::::casa Consolidate the sub-tables
13-Mar-2026 00:55:38	2026-03-13 04:55:39 INFO MSTransformDataHandler::mergeSpwSubTables Merging SPECTRAL_WINDOW sub-tables from all sub-MSs to form MMS-level SPECTRAL_WINDOW sub-table
13-Mar-2026 00:55:38	2026-03-13 04:55:39 INFO MSTransformDataHandler::mergeDDISubTables Merging DDI sub-tables from all sub-MSs to form MMS-level DDI sub-table
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO MSTransformDataHandler::mergeFeedSubTables Merging FEED sub-tables from all sub-MSs to form MMS-level FEED sub-table
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO MSTransformDataHandler::mergeSourceSubTables Merging SOURCE sub-tables from all sub-MSs to form MMS-level SOURCE sub-table
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO MSTransformDataHandler::mergeCalDeviceSubtables Merging CALDEVICE sub-tables from all sub-MSs to form MMS-level CALDEVICE sub-table
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO MSTransformDataHandler::mergeSysPowerSubtables Merging SYSPOWER sub-tables from all sub-MSs to form MMS-level SYSPOWER sub-table
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO MSTransformDataHandler::mergeSyscalSubTables Merging SYSCAL sub-tables from all sub-MSs to form MMS-level SYSCAL sub-table
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO ParallelDataHelper::::casa Finalizing MMS structure
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO ms::createmultims Copying subtables from /home/casatest/work/test_task_mstransform_mms/test_vla_mixed_polarizations_2.mms.data/test_vla_mixed_polarizations_2.mms.0000.ms to the other MMS members.
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO mstransform::::casa Task mstransform complete. Start time: 2026-03-13 04:55:33.976200 End time: 2026-03-13 04:55:39.473124
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO mstransform::::casa ##### End Task: mstransform #####
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO mstransform::::casa ##########################################
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO flagdata::::casa ##########################################
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO flagdata::::casa ##### Begin Task: flagdata #####
13-Mar-2026 00:55:57	2026-03-13 04:55:39 INFO flagdata::::casa flagdata( vis='test_vla_mixed_polarizations_2.mms', mode='summary', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='', field='', antenna='', uvrange='', timerange='', correlation='', scan='', intent='', array='', observation='', feed='', clipminmax=[], datacolumn='DATA', clipoutside=True, channelavg=False, chanbin=1, timeavg=False, timebin='0s', clipzeros=False, quackinterval=1.0, quackmode='beg', quackincrement=False, tolerance=0.0, addantenna='', lowerlimit=0.0, upperlimit=90.0, ntime='scan', combinescans=False, timecutoff=4.0, freqcutoff=3.0, timefit='line', freqfit='poly', maxnpieces=7, flagdimension='freqtime', usewindowstats='none', halfwin=1, extendflags=True, winsize=3, timedev='', freqdev='', timedevscale=5.0, freqdevscale=5.0, spectralmax=1000000.0, spectralmin=0.0, antint_ref_antenna='', minchanfrac=0.6, verbose=False, extendpols=True, growtime=50.0, growfreq=50.0, growaround=False, flagneartime=False, flagnearfreq=False, minrel=0.0, maxrel=1.0, minabs=0, maxabs=-1, spwchan=False, spwcorr=False, basecnt=False, fieldcnt=False, name='Summary', action='apply', display='', flagbackup=True, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
13-Mar-2026 00:55:57	2026-03-13 04:55:40 WARN ParallelTaskHelper::consolidateResults::casa ***** consolidateResults, subMS: {'antenna': {'ea01': {'flagged': 0.0, 'total': 128.0}, 'ea02': {'flagged': 0.0, 'total': 128.0}}, 'array': {'0': {'flagged': 0.0, 'total': 128.0}}, 'correlation': {'XX': {'flagged': 0.0, 'total': 128.0}}, 'field': {'J1911+1611': {'flagged': 0.0, 'total': 128.0}}, 'flagged': 0.0, 'name': 'Summary', 'observation': {'0': {'flagged': 0.0, 'total': 128.0}}, 'scan': {'16': {'flagged': 0.0, 'total': 128.0}}, 'spw': {'0': {'flagged': 0.0, 'total': 128.0}}, 'total': 128.0, 'type': 'summary'}
13-Mar-2026 00:55:57	2026-03-13 04:55:40 WARN ParallelTaskHelper::consolidateResults::casa ***** consolidateResults, subMS: {'antenna': {'ea01': {'flagged': 0.0, 'total': 128.0}, 'ea02': {'flagged': 0.0, 'total': 128.0}}, 'array': {'0': {'flagged': 0.0, 'total': 128.0}}, 'correlation': {'XX': {'flagged': 0.0, 'total': 128.0}}, 'field': {'J1911+1611': {'flagged': 0.0, 'total': 128.0}}, 'flagged': 0.0, 'name': 'Summary', 'observation': {'0': {'flagged': 0.0, 'total': 128.0}}, 'scan': {'16': {'flagged': 0.0, 'total': 128.0}}, 'spw': {'1': {'flagged': 0.0, 'total': 128.0}}, 'total': 128.0, 'type': 'summary'}
13-Mar-2026 00:55:57	2026-03-13 04:55:40 WARN ParallelTaskHelper::consolidateResults::casa ***** consolidateResults, subMS: {'antenna': {'ea01': {'flagged': 0.0, 'total': 128.0}, 'ea02': {'flagged': 0.0, 'total': 128.0}}, 'array': {'0': {'flagged': 0.0, 'total': 128.0}}, 'correlation': {'XX': {'flagged': 0.0, 'total': 128.0}}, 'field': {'J1911+1611': {'flagged': 0.0, 'total': 128.0}}, 'flagged': 0.0, 'name': 'Summary', 'observation': {'0': {'flagged': 0.0, 'total': 128.0}}, 'scan': {'16': {'flagged': 0.0, 'total': 128.0}}, 'spw': {'2': {'flagged': 0.0, 'total': 128.0}}, 'total': 128.0, 'type': 'summary'}
13-Mar-2026 00:55:57	2026-03-13 04:55:40 INFO flagdata::::casa Task flagdata complete. Start time: 2026-03-13 04:55:39.483643 End time: 2026-03-13 04:55:40.023088
13-Mar-2026 00:55:57	2026-03-13 04:55:40 INFO flagdata::::casa ##### End Task: flagdata #####
13-Mar-2026 00:55:57	2026-03-13 04:55:40 INFO flagdata::::casa ##########################################
13-Mar-2026 00:55:57	.
13-Mar-2026 00:55:57	----------------------------------------------------------------------
13-Mar-2026 00:55:57	Ran 47 tests in 209.431s
13-Mar-2026 00:55:57
13-Mar-2026 00:55:57	OK (skipped=1)
13-Mar-2026 00:55:57	Marking test as passed.
13-Mar-2026 00:55:57	Executor result: 0
13-Mar-2026 00:55:57	Failed tests:[]
13-Mar-2026 00:55:57
13-Mar-2026 00:55:57	Results: [{'testname': 'test_task_sdintimaging', 'runtime': '0:39:40.816780', 'returncode': 0, 'testerr': []}, {'testname': 'test_task_deconvolve', 'runtime': '0:34:30.497687', 'returncode': 0, 'testerr': []}, {'testname': 'test_task_mstransform_mms', 'runtime': '0:03:51.594615', 'returncode': 0, 'testerr': []}]
13-Mar-2026 00:55:57	Stopping Xvfb.
13-Mar-2026 00:55:57	Stopping framebuffer <Popen: returncode: None args: ['Xvfb', ':112', '-screen', '0', '2048x2048x2...>
13-Mar-2026 00:55:57	Virtual frame buffer deployed at :112 with pid 122 successfully shutdown
13-Mar-2026 00:55:57	Xvfb stopped.
13-Mar-2026 00:55:58	total 821600
13-Mar-2026 00:55:58	-rw-r--r-- 1 casaci 15001 841312680 Mar 13 03:36 casa-6.7.4-1-py3.12.el8.tar.xz
13-Mar-2026 00:55:58	-rw-r--r-- 1 casaci casatest 594 Mar 13 04:55 xUnit-suite-mpi-8.xml
13-Mar-2026 00:55:58	Finished task 'MPI Tasks Tests on EL8' with result: Success
13-Mar-2026 00:55:58	Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
13-Mar-2026 00:55:58	Parsing test results under /export/data_1/bamboohome/xml-data/build-dir/CASASPECS-RELEASE674TESTCASALITHPY312-MTR85...
13-Mar-2026 00:55:58	Finished task 'Parse test results' with result: Success
13-Mar-2026 00:55:58	Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
13-Mar-2026 00:55:58	Beginning to execute external process for build 'CASASPECS - Release 6.7.4 Test Casalith 3.12 - Test MPI Task Tests Many Linux 2.28 Rocky 8.10 Docker #10 (CASASPECS-RELEASE674TESTCASALITHPY312-MTR85-10)' ... running command line: /export/data_1/bamboohome/temp/CASASPECS-RELEASE674TESTCASALITHPY312-MTR85-10-ScriptBuildTask-12492863939364697686.sh ... in: /export/data_1/bamboohome/xml-data/build-dir/CASASPECS-RELEASE674TESTCASALITHPY312-MTR85
13-Mar-2026 00:55:58	Starting cleanup
13-Mar-2026 00:56:08	test-container
13-Mar-2026 00:56:10	test-container
13-Mar-2026 00:56:10	Cleanup complete
13-Mar-2026 00:56:10	Finished task 'Cleanup' with result: Success
13-Mar-2026 00:56:10	Running post build plugin 'Docker Container Cleanup'
13-Mar-2026 00:56:10	Running post build plugin 'NCover Results Collector'
13-Mar-2026 00:56:10	Running post build plugin 'Build Results Label Collector'
13-Mar-2026 00:56:10	Running post build plugin 'Clover Results Collector'
13-Mar-2026 00:56:10	Running post build plugin 'npm Cache Cleanup'
13-Mar-2026 00:56:10	Running post build plugin 'Artifact Copier'
13-Mar-2026 00:56:10	Finalising the build...
13-Mar-2026 00:56:10	Stopping timer.
13-Mar-2026 00:56:10	Build CASASPECS-RELEASE674TESTCASALITHPY312-MTR85-10 completed.
13-Mar-2026 00:56:11	Running on server: post build plugin 'Build Hanging Detection Configuration'
13-Mar-2026 00:56:11	Running on server: post build plugin 'NCover Results Collector'
13-Mar-2026 00:56:11	Running on server: post build plugin 'Build Labeller'
13-Mar-2026 00:56:11	Running on server: post build plugin 'Clover Delta Calculator'
13-Mar-2026 00:56:11	Running on server: post build plugin 'Maven Dependencies Postprocessor'
13-Mar-2026 00:56:11	All post build plugins have finished
13-Mar-2026 00:56:11	Generating build results summary...
13-Mar-2026 00:56:11	Saving build results to disk...
13-Mar-2026 00:56:11	Store variable context...
13-Mar-2026 00:56:11	Finished building CASASPECS-RELEASE674TESTCASALITHPY312-MTR85-10.

Build: #10 was successful

Job: Test MPI Task Tests Many Linux 2.28 Rocky 8.10 Docker was successful

Stages & jobs

Default Stage

Build log

Build #10

Build: #10 was successful

Job: Test MPI Task Tests Many Linux 2.28 Rocky 8.10 Docker was successful

Stages & jobs

Default Stage

Build log