Build log

The build generated 22,802 lines of output.The output is too long and has been truncated to the last 1,000 lines. Download or view full build log. You can find logs from previous runs here.

03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms3.mms.data/testmms3.mms.0000.ms
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::parseDataSelParams scan selection is 30
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::parseDataSelParams spw selection is 0:0~10,1:60~63
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::initDataSelectionParams Selected Scans Ids are [30]
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::initDataSelectionParams+ [0, 0, 10, 1
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:07	2024-10-03 14:30:06 INFO MSTransformDataHandler::makeSelection 1068 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:06.225426 End time: 2024-10-03 14:30:06.883974
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:07	2024-10-03 14:30:07 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 )
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms3.mms.data/testmms3.mms.0001.ms
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::parseDataSelParams scan selection is 31
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::parseDataSelParams spw selection is 0:0~10,1:60~63
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::initDataSelectionParams Selected Scans Ids are [31]
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::initDataSelectionParams+ [0, 0, 10, 1
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO MSTransformDataHandler::makeSelection 1080 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:06.889494 End time: 2024-10-03 14:30:07.481680
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:07	2024-10-03 14:30:07 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:07	2024-10-03 14:30:07 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.
03-Oct-2024 14:30:07	2024-10-03 14:30:08 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:06.180706 End time: 2024-10-03 14:30:07.686859
03-Oct-2024 14:30:07	2024-10-03 14:30:08 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:07	2024-10-03 14:30:08 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:07	.2024-10-03 14:30:08 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:07	2024-10-03 14:30:08 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:09	2024-10-03 14:30:08 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 )
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:09	2024-10-03 14:30:08 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 )
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0000.ms
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseDataSelParams spw selection is 3
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::initDataSelectionParams+ [3, 0, 63, 1]
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:07.851299 End time: 2024-10-03 14:30:08.465901
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:09	2024-10-03 14:30:08 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 )
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0001.ms
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseDataSelParams spw selection is 5:10~20
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::parseFreqTransParams DDI start is 1
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::initDataSelectionParams+ [5, 10, 20, 1]
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:09	2024-10-03 14:30:08 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:09	2024-10-03 14:30:09 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:09	2024-10-03 14:30:09 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:08.471499 End time: 2024-10-03 14:30:09.041583
03-Oct-2024 14:30:09	2024-10-03 14:30:09 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:09	2024-10-03 14:30:09 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:09	2024-10-03 14:30:09 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:09	2024-10-03 14:30:09 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:09	2024-10-03 14:30:09 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 )
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0002.ms
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::parseDataSelParams spw selection is 7
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::parseFreqTransParams DDI start is 2
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::initDataSelectionParams+ [7, 0, 63, 1]
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:10	2024-10-03 14:30:09 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:09.047132 End time: 2024-10-03 14:30:09.616619
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:10	2024-10-03 14:30:10 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 )
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0003.ms
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseDataSelParams spw selection is 11
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseFreqTransParams DDI start is 3
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::initDataSelectionParams+ [11, 0, 63, 1]
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:09.622263 End time: 2024-10-03 14:30:10.197170
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:10	2024-10-03 14:30:10 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 )
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms4.mms.data/testmms4.mms.0004.ms
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseDataSelParams spw selection is 13
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::parseFreqTransParams DDI start is 4
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [1, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::initDataSelectionParams+ [13, 0, 63, 1]
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:10	2024-10-03 14:30:10 INFO MSTransformDataHandler::makeSelection 1074 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:10	2024-10-03 14:30:11 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:10	2024-10-03 14:30:11 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:10.202726 End time: 2024-10-03 14:30:10.813707
03-Oct-2024 14:30:10	2024-10-03 14:30:11 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:10	2024-10-03 14:30:11 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:10	2024-10-03 14:30:11 INFO ParallelDataHelper::::casa Consolidate the sub-tables
03-Oct-2024 14:30:10	2024-10-03 14:30:11 INFO MSTransformDataHandler::mergeSpwSubTables Merging SPECTRAL_WINDOW sub-tables from all sub-MSs to form MMS-level SPECTRAL_WINDOW sub-table
03-Oct-2024 14:30:10	2024-10-03 14:30:11 INFO MSTransformDataHandler::mergeDDISubTables Merging DDI sub-tables from all sub-MSs to form MMS-level DDI sub-table
03-Oct-2024 14:30:17	2024-10-03 14:30:11 INFO MSTransformDataHandler::mergeFeedSubTables Merging FEED sub-tables from all sub-MSs to form MMS-level FEED sub-table
03-Oct-2024 14:30:17	2024-10-03 14:30:11 INFO MSTransformDataHandler::mergeSourceSubTables Merging SOURCE sub-tables from all sub-MSs to form MMS-level SOURCE sub-table
03-Oct-2024 14:30:17	2024-10-03 14:30:11 INFO MSTransformDataHandler::mergeSyscalSubTables Merging SYSCAL sub-tables from all sub-MSs to form MMS-level SYSCAL sub-table
03-Oct-2024 14:30:17	2024-10-03 14:30:11 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:17	2024-10-03 14:30:11 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.
03-Oct-2024 14:30:17	2024-10-03 14:30:12 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:07.803994 End time: 2024-10-03 14:30:11.671335
03-Oct-2024 14:30:17	2024-10-03 14:30:12 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:17	2024-10-03 14:30:12 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:17	.2024-10-03 14:30:12 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:17	2024-10-03 14:30:12 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:17	2024-10-03 14:30:12 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 )
03-Oct-2024 14:30:17	2024-10-03 14:30:12 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:17	2024-10-03 14:30:14 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:17	2024-10-03 14:30:14 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.
03-Oct-2024 14:30:17	2024-10-03 14:30:15 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:11.867388 End time: 2024-10-03 14:30:15.054950
03-Oct-2024 14:30:17	2024-10-03 14:30:15 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:17	2024-10-03 14:30:15 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:17	.2024-10-03 14:30:15 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:17	2024-10-03 14:30:15 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:17	2024-10-03 14:30:15 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 )
03-Oct-2024 14:30:17	2024-10-03 14:30:15 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:17	2024-10-03 14:30:15 INFO ParallelDataHelper::::casa Automatically distribute the scan/spw pairs to balance the load along field, spw and scan axes
03-Oct-2024 14:30:17	2024-10-03 14:30:15 INFO msmetadata_cmpt.cc::open Performing internal consistency checks on /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms...
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:17	2024-10-03 14:30:17 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.
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:15.211190 End time: 2024-10-03 14:30:17.042332
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:17	2024-10-03 14:30:17 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 )
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:17	2024-10-03 14:30:17 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 )
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO mstransform::::casa Combine spws 0:60~63,1:60~63 into new output spw
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/inpmms14.mms/SUBMSS/inpmms14.mms.0000.ms
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO MSTransformManager::parseMsSpecParams Data column is DATA
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0000.ms
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO MSTransformManager::parseDataSelParams spw selection is 0:60~63,1:60~63
03-Oct-2024 14:30:17	2024-10-03 14:30:17 INFO MSTransformManager::parseFreqTransParams Combine Spectral Windows is activated
03-Oct-2024 14:30:18	2024-10-03 14:30:17 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input DATA column
03-Oct-2024 14:30:18	2024-10-03 14:30:17 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:18	2024-10-03 14:30:17 INFO MSTransformManager::initDataSelectionParams+ [0, 60, 63, 1
03-Oct-2024 14:30:18	2024-10-03 14:30:17 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
03-Oct-2024 14:30:18	2024-10-03 14:30:17 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:18	2024-10-03 14:30:17 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::regridAndCombineSpwSubtable Calculate combined SPW frequencies
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::combineSpwsCore Input SPWs sorted by first (lowest) channel frequency:
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::combineSpwsCore SPW 0: 4 channels, first channel = 1.696000000e+09 Hz, last channel = 1.699000000e+09 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::combineSpwsCore SPW 1: 4 channels, first channel = 1.760000000e+09 Hz, last channel = 1.763000000e+09 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 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
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::regridSpwAux Calculate frequencies in output reference frame
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs Channels equidistant in freq
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Central frequency (in output frame) = 1.7295e+09 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Width of central channel (in output frame) = 1e+06 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Number of channels = 68
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Total width of SPW (in output frame) = 6.8e+07 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Lower edge = 1.6955e+09 Hz, upper edge = 1.7635e+09 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 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
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::regridAndCombineSpwSubtable Write output SPW subtable
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::reindexDDISubTable Re-indexing DDI sub-table
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::reindexSourceSubTable Re-indexing SOURCE sub-table
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::reindexGenericTimeDependentSubTable Re-indexing SPW column of FEED sub-table and removing duplicates
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::setIterationApproach Combining data from selected spectral windows
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:17.175741 End time: 2024-10-03 14:30:17.864295
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:18	2024-10-03 14:30:18 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 )
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO mstransform::::casa Combine spws 0:60~63,1:60~63 into new output spw
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/inpmms14.mms/SUBMSS/inpmms14.mms.0001.ms
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::parseMsSpecParams Data column is DATA
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0001.ms
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::parseDataSelParams spw selection is 0:60~63,1:60~63
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::parseFreqTransParams Combine Spectral Windows is activated
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input DATA column
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::initDataSelectionParams+ [0, 60, 63, 1
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::regridAndCombineSpwSubtable Calculate combined SPW frequencies
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::combineSpwsCore Input SPWs sorted by first (lowest) channel frequency:
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::combineSpwsCore SPW 0: 4 channels, first channel = 1.696000000e+09 Hz, last channel = 1.699000000e+09 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::combineSpwsCore SPW 1: 4 channels, first channel = 1.760000000e+09 Hz, last channel = 1.763000000e+09 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 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
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::regridSpwAux Calculate frequencies in output reference frame
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs Channels equidistant in freq
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Central frequency (in output frame) = 1.7295e+09 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Width of central channel (in output frame) = 1e+06 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Number of channels = 68
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Total width of SPW (in output frame) = 6.8e+07 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformRegridder::calcChanFreqs+ Lower edge = 1.6955e+09 Hz, upper edge = 1.7635e+09 Hz
03-Oct-2024 14:30:18	2024-10-03 14:30:18 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
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::regridAndCombineSpwSubtable Write output SPW subtable
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::reindexDDISubTable Re-indexing DDI sub-table
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::reindexSourceSubTable Re-indexing SOURCE sub-table
03-Oct-2024 14:30:18	2024-10-03 14:30:18 INFO MSTransformManager::reindexGenericTimeDependentSubTable Re-indexing SPW column of FEED sub-table and removing duplicates
03-Oct-2024 14:30:20	2024-10-03 14:30:18 INFO MSTransformManager::setIterationApproach Combining data from selected spectral windows
03-Oct-2024 14:30:20	2024-10-03 14:30:18 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:17.869934 End time: 2024-10-03 14:30:18.508520
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:20	2024-10-03 14:30:19 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 )
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa Combine spws 0:60~63,1:60~63 into new output spw
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/inpmms14.mms/SUBMSS/inpmms14.mms.0002.ms
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::parseMsSpecParams Data column is DATA
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/combspw14.ms.data/combspw14.ms.0002.ms
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::parseDataSelParams spw selection is 0:60~63,1:60~63
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::parseFreqTransParams Combine Spectral Windows is activated
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input DATA column
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::initDataSelectionParams Selected SPWs Ids are Axis Lengths: [2, 4] (NB: Matrix in Row/Column order)
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::initDataSelectionParams+ [0, 60, 63, 1
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::initDataSelectionParams+ 1, 60, 63, 1]
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::regridAndCombineSpwSubtable Calculate combined SPW frequencies
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformRegridder::combineSpwsCore Input SPWs sorted by first (lowest) channel frequency:
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformRegridder::combineSpwsCore SPW 0: 4 channels, first channel = 1.696000000e+09 Hz, last channel = 1.699000000e+09 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformRegridder::combineSpwsCore SPW 1: 4 channels, first channel = 1.760000000e+09 Hz, last channel = 1.763000000e+09 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:19 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
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::regridSpwAux Calculate frequencies in output reference frame
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformRegridder::calcChanFreqs Channels equidistant in freq
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformRegridder::calcChanFreqs+ Central frequency (in output frame) = 1.7295e+09 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformRegridder::calcChanFreqs+ Width of central channel (in output frame) = 1e+06 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformRegridder::calcChanFreqs+ Number of channels = 68
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformRegridder::calcChanFreqs+ Total width of SPW (in output frame) = 6.8e+07 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformRegridder::calcChanFreqs+ Lower edge = 1.6955e+09 Hz, upper edge = 1.7635e+09 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:19 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
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::regridAndCombineSpwSubtable Write output SPW subtable
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::reindexDDISubTable Re-indexing DDI sub-table
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::reindexSourceSubTable Re-indexing SOURCE sub-table
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::reindexGenericTimeDependentSubTable Re-indexing SPW column of FEED sub-table and removing duplicates
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO MSTransformManager::setIterationApproach Combining data from selected spectral windows
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:18.513986 End time: 2024-10-03 14:30:19.141936
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:20	2024-10-03 14:30:19 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:20	2024-10-03 14:30:19 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.
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:17.048175 End time: 2024-10-03 14:30:19.514872
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO cvel::::casa ##########################################
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO cvel::::casa ##### Begin Task: cvel #####
03-Oct-2024 14:30:20	2024-10-03 14:30:20 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 )
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO cvel::::casa Creating selected SubMS ...
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO SubMS::parseColumnNames() Using DATA MODEL_DATA CORRECTED_DATA columns.
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO SubMS::makeSelection() 2148 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO SubMS::doChannelMods() Data binned.
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO cvel::::casa Using 3C286_A (original field 0, new field 0) as common direction for the output reference frame.
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO ms::cvel Input spectral window 0 has 4 channels of width 1.000000e+06 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO ms::cvel+ First channel center = 1.696000000e+09 Hz, last channel center = 1.699000000e+09 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO ms::cvel Input spectral window 1 has 4 channels of width 1.000000e+06 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO ms::cvel+ First channel center = 1.760000000e+09 Hz, last channel center = 1.763000000e+09 Hz
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO ms::cvel Starting combination of spectral windows ...
03-Oct-2024 14:30:20	2024-10-03 14:30:20 INFO SubMS::combineSpws() Input SPWs sorted by first (lowest) channel frequency:
03-Oct-2024 14:30:22	2024-10-03 14:30:20 INFO SubMS::combineSpws() SPW 0: 4 channels, first channel = 1.696000000e+09 Hz, last channel = 1.699000000e+09 Hz
03-Oct-2024 14:30:22	2024-10-03 14:30:20 INFO SubMS::combineSpws() SPW 1: 4 channels, first channel = 1.760000000e+09 Hz, last channel = 1.763000000e+09 Hz
03-Oct-2024 14:30:22	2024-10-03 14:30:20 INFO SubMS::combineSpws() Combined SPW will have 8 channels. May change in later regridding.
03-Oct-2024 14:30:22	combineSpws progress: 20% processed ...
03-Oct-2024 14:30:22	combineSpws progress: 40% processed ...
03-Oct-2024 14:30:22	combineSpws progress: 60% processed ...
03-Oct-2024 14:30:22	combineSpws progress: 80% processed ...
03-Oct-2024 14:30:22	combineSpws progress: 100% processed.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::combineSpws() Processed 2148 original rows, wrote 1074 new ones.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::combineSpws() Spectral window combination complete.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO ms::cvel
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO ms::cvel Testing if spectral frame transformation/regridding is needed ...
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw() Using tabulated observatory position for EVLA:
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw() Position: [-1.60116e+06, -5.04199e+06, 3.55488e+06] (ITRF)
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw() Regridded spectral window 0 will be created for field 1 with parameters
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ input frame = TOPO, output frame = TOPO
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ Channels equidistant in freq
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ Central frequency (in output frame) = 1.7295e+09 Hz
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ Width of central channel (in output frame) = 1e+06 Hz
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ Number of channels = 68
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ Total width of SPW (in output frame) = 6.8e+07 Hz
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ Lower edge = 1.6955e+09 Hz, upper edge = 1.7635e+09 Hz
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ Interpolation Method = linear
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw() Added 1 new rows to the DATA_DESCRIPTION table and deleted 1 old ones.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ Added 1 rows to the SPECTRAL_WINDOW table and deleted 1 old ones.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw()+ Added 1 rows to the SOURCE table and deleted 2 old ones.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO SubMS::regridSpw() Main table data array columns will be rewritten.
03-Oct-2024 14:30:22	regridSpw progress: 20% processed ...
03-Oct-2024 14:30:22	regridSpw progress: 40% processed ...
03-Oct-2024 14:30:22	regridSpw progress: 60% processed ...
03-Oct-2024 14:30:22	regridSpw progress: 80% processed ...
03-Oct-2024 14:30:22	regridSpw progress: 100% processed.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO ms::cvel Spectral frame transformation/regridding completed.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO ms::cvel Final spectral window has 68 channels of width 1.000000e+06 Hz
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO ms::cvel+ First channel center = 1.696000000e+09 Hz, last channel center = 1.763000000e+09 Hz
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO ms::cvel NOTE: any virtual model data will be cleared.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO Clearing all model records in MS header.
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO cvel::::casa Task cvel complete. Start time: 2024-10-03 14:30:19.535148 End time: 2024-10-03 14:30:20.875286
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO cvel::::casa ##### End Task: cvel #####
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO cvel::::casa ##########################################
03-Oct-2024 14:30:22	.2024-10-03 14:30:21 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:22	2024-10-03 14:30:21 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 )
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:22	2024-10-03 14:30:21 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 )
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms13.mms.data/testmms13.mms.0000.ms
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::parseDataSelParams scan selection is 30
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::initDataSelectionParams Selected Scans Ids are [30]
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:22	2024-10-03 14:30:21 INFO MSTransformDataHandler::makeSelection 8544 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:22	2024-10-03 14:30:22 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:22	2024-10-03 14:30:22 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:21.056258 End time: 2024-10-03 14:30:22.256897
03-Oct-2024 14:30:22	2024-10-03 14:30:22 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:22	2024-10-03 14:30:22 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:22	2024-10-03 14:30:22 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:22	2024-10-03 14:30:22 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:25	2024-10-03 14:30:22 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 )
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::parseMsSpecParams Input file name is /home/casatest/work/test_task_mstransform_mms/Four_ants_3C286.ms
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::parseMsSpecParams Data column is CORRECTED
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::parseMsSpecParams Output file name is /home/casatest/work/test_task_mstransform_mms/testmms13.mms.data/testmms13.mms.0001.ms
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::parseDataSelParams scan selection is 31
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input CORRECTED_DATA column
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::initDataSelectionParams Selected Scans Ids are [31]
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:25	2024-10-03 14:30:22 INFO MSTransformDataHandler::makeSelection 8640 out of 17184 rows are going to be considered due to the selection criteria.
03-Oct-2024 14:30:25	2024-10-03 14:30:23 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:25	2024-10-03 14:30:23 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:22.262928 End time: 2024-10-03 14:30:23.459540
03-Oct-2024 14:30:25	2024-10-03 14:30:23 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:25	2024-10-03 14:30:23 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:25	2024-10-03 14:30:23 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:25	2024-10-03 14:30:23 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.
03-Oct-2024 14:30:25	2024-10-03 14:30:24 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:21.005779 End time: 2024-10-03 14:30:23.666804
03-Oct-2024 14:30:25	2024-10-03 14:30:24 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:25	2024-10-03 14:30:24 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:25	2024-10-03 14:30:24 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:25	2024-10-03 14:30:24 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:25	2024-10-03 14:30:24 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 )
03-Oct-2024 14:30:25	2024-10-03 14:30:24 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:25	2024-10-03 14:30:24 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:25	2024-10-03 14:30:25 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.
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:23.672330 End time: 2024-10-03 14:30:25.433918
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::::casa ##########################################
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::::casa ##### Begin Task: listobs #####
03-Oct-2024 14:30:25	2024-10-03 14:30:25 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 )
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary ================================================================================
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ MeasurementSet Name: /home/casatest/work/test_task_mstransform_mms/reg13.ms MS Version 2
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ ================================================================================
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ Observer: Ms. Urvashi Rao Venkata Project: T.B.D.
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ Observation: EVLA
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::MSMetaData::_computeScanAndSubScanProperties Computing scan and subscan properties...
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary Data records: 4296 Total elapsed time = 179.8 seconds
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ Observed from 16-Oct-2010/14:45:08.0 to 16-Oct-2010/14:48:07.8 (UTC)
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ ObservationID = 0 ArrayID = 0
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ Date Timerange (UTC) Scan FldId FieldName nRows SpwIds Average Interval(s) ScanIntent
03-Oct-2024 14:30:25	2024-10-03 14:30:25 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]
03-Oct-2024 14:30:25	2024-10-03 14:30:25 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]
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary (nRows = Total number of rows per scan)
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary Fields: 1
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ ID Code Name RA Decl Epoch SrcId nRows
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ 1 Q 3C286_A 13:31:08.287985 +30.30.32.95886 J2000 1 4296
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary Spectral Windows: (4 unique spectral windows and 1 unique polarization setups)
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ SpwID Name #Chans Frame Ch0(MHz) ChanWid(kHz) TotBW(kHz) CtrFreq(MHz) Corrs
03-Oct-2024 14:30:25	2024-10-03 14:30:25 INFO listobs::ms::summary+ 0 Subband:1 64 TOPO 1700.000 1000.000 64000.0 1731.5000 RR RL LR LL
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 1 Subband:3 64 TOPO 1828.000 1000.000 64000.0 1859.5000 RR RL LR LL
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 2 Subband:5 64 TOPO 1956.000 1000.000 64000.0 1987.5000 RR RL LR LL
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 3 Subband:7 64 TOPO 2084.000 1000.000 64000.0 2115.5000 RR RL LR LL
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary Sources: 8
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ ID Name SpwId RestFreq(MHz) SysVel(km/s)
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 0 3C286_A 0 - -
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 0 3C286_A 1 - -
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 0 3C286_A 2 - -
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 0 3C286_A 3 - -
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 1 3C286_A 0 - -
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 1 3C286_A 1 - -
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 1 3C286_A 2 - -
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ 1 3C286_A 3 - -
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary Antennas: 4:
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ ID Name Station Diam. Long. Lat. Offset from array center (m) ITRF Geocentric coordinates (m)
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::ms::summary+ East North Elevation x y z
03-Oct-2024 14:30:28	2024-10-03 14:30:25 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
03-Oct-2024 14:30:28	2024-10-03 14:30:25 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
03-Oct-2024 14:30:28	2024-10-03 14:30:25 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
03-Oct-2024 14:30:28	2024-10-03 14:30:25 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
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::::casa Task listobs complete. Start time: 2024-10-03 14:30:25.447025 End time: 2024-10-03 14:30:25.467538
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::::casa ##### End Task: listobs #####
03-Oct-2024 14:30:28	2024-10-03 14:30:25 INFO listobs::::casa ##########################################
03-Oct-2024 14:30:28	.s2024-10-03 14:30:26 INFO partition::::casa ##########################################
03-Oct-2024 14:30:28	2024-10-03 14:30:26 INFO partition::::casa ##### Begin Task: partition #####
03-Oct-2024 14:30:28	2024-10-03 14:30:26 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='' )
03-Oct-2024 14:30:28	2024-10-03 14:30:26 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:28	2024-10-03 14:30:26 INFO ParallelDataHelper::::casa Automatically distribute the scan/spw pairs to balance the load along field, spw and scan axes
03-Oct-2024 14:30:28	2024-10-03 14:30:26 INFO msmetadata_cmpt.cc::open Performing internal consistency checks on /home/casatest/work/test_task_mstransform_mms/test-subtables-alma.ms...
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:28	2024-10-03 14:30:28 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.
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO partition::::casa Create a backup of the flags that are in the MMS
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO AgentFlagger::open Table type is Measurement Set
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO FlagVersion::saveFlagVersion Creating new backup flag file called partition_1
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO partition::::casa Task partition complete. Start time: 2024-10-03 14:30:25.602431 End time: 2024-10-03 14:30:28.353844
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO partition::::casa ##### End Task: partition #####
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO partition::::casa ##########################################
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:28	2024-10-03 14:30:28 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 )
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:28	2024-10-03 14:30:28 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
03-Oct-2024 14:30:28	2024-10-03 14:30:29 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):
03-Oct-2024 14:30:28	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.0-7-py3.8.el8/lib/py/lib/python3.8/site-packages/casampi/private/MPICommandServer.py", line 208, in __command_request_handler_service
03-Oct-2024 14:30:28	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ command_response['ret'] = eval(eval_str)
03-Oct-2024 14:30:28	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "<string>", line 1, in <module>
03-Oct-2024 14:30:28	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.0-7-py3.8.el8/lib/py/lib/python3.8/site-packages/casatasks/mstransform.py", line 620, in __call__
03-Oct-2024 14:30:32	2024-10-03 14:30:29 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'] )
03-Oct-2024 14:30:32	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.0-7-py3.8.el8/lib/py/lib/python3.8/site-packages/casatasks/private/task_mstransform.py", line 293, in mstransform
03-Oct-2024 14:30:32	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ mtlocal.open()
03-Oct-2024 14:30:32	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.0-7-py3.8.el8/lib/py/lib/python3.8/site-packages/casatools/mstransformer.py", line 36, in open
03-Oct-2024 14:30:32	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ return self._swigobj.open()
03-Oct-2024 14:30:32	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ File "/home/casatest/work/pkg/casa-6.7.0-7-py3.8.el8/lib/py/lib/python3.8/site-packages/casatools/__casac__/mstransformer.py", line 176, in open
03-Oct-2024 14:30:32	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ return _mstransformer.mstransformer_open(self)
03-Oct-2024 14:30:32	2024-10-03 14:30:29 SEVERE MPICommandClient::command_response_handler_service::MPICommandClient::command_response_handler_service::casa+ RuntimeError: MSSelectionNullSelection : The selected table has zero rows.
03-Oct-2024 14:30:32	2024-10-03 14:30:30 SEVERE MeasTable::dUTC(Double)::MPIServer-2 (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290) Leap second table TAI_UTC seems out-of-date.
03-Oct-2024 14:30:32	2024-10-03 14:30:30 SEVERE MeasTable::dUTC(Double)::MPIServer-2 (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290)+ Until the table is updated (see the CASA documentation or your system admin),
03-Oct-2024 14:30:32	2024-10-03 14:30:30 SEVERE MeasTable::dUTC(Double)::MPIServer-2 (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290)+ times and coordinates derived from UTC could be wrong by 1s or more.
03-Oct-2024 14:30:32	2024-10-03 14:30:30 SEVERE MeasTable::dUTC(Double)::MPIServer-1 (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290) Leap second table TAI_UTC seems out-of-date.
03-Oct-2024 14:30:32	2024-10-03 14:30:30 SEVERE MeasTable::dUTC(Double)::MPIServer-1 (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290)+ Until the table is updated (see the CASA documentation or your system admin),
03-Oct-2024 14:30:32	2024-10-03 14:30:30 SEVERE MeasTable::dUTC(Double)::MPIServer-1 (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290)+ times and coordinates derived from UTC could be wrong by 1s or more.
03-Oct-2024 14:30:32	2024-10-03 14:30:30 INFO ParallelDataHelper::::casa 1 subMSs failed to be created. This is not an error, if due to selection when creating a Multi-MS
03-Oct-2024 14:30:32	2024-10-03 14:30:30 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:32	2024-10-03 14:30:30 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.
03-Oct-2024 14:30:32	2024-10-03 14:30:30 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:28.359168 End time: 2024-10-03 14:30:30.303743
03-Oct-2024 14:30:32	2024-10-03 14:30:30 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:32	2024-10-03 14:30:30 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:32	.2024-10-03 14:30:31 INFO partition::::casa ##########################################
03-Oct-2024 14:30:32	2024-10-03 14:30:31 INFO partition::::casa ##### Begin Task: partition #####
03-Oct-2024 14:30:32	2024-10-03 14:30:31 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='' )
03-Oct-2024 14:30:32	2024-10-03 14:30:31 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:32	2024-10-03 14:30:31 INFO ParallelDataHelper::::casa Automatically distribute the scan/spw pairs to balance the load along field, spw and scan axes
03-Oct-2024 14:30:32	2024-10-03 14:30:31 INFO msmetadata_cmpt.cc::open Performing internal consistency checks on /home/casatest/work/test_task_mstransform_mms/mstransform_reference/ngc5921.ms...
03-Oct-2024 14:30:32	2024-10-03 14:30:32 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:32	2024-10-03 14:30:32 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.
03-Oct-2024 14:30:32	2024-10-03 14:30:33 INFO partition::::casa Create a backup of the flags that are in the MMS
03-Oct-2024 14:30:32	2024-10-03 14:30:33 INFO AgentFlagger::open Table type is Measurement Set
03-Oct-2024 14:30:32	2024-10-03 14:30:33 INFO FlagVersion::saveFlagVersion Creating new backup flag file called partition_1
03-Oct-2024 14:30:32	2024-10-03 14:30:33 SEVERE MeasTable::dUTC(Double) (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290) Leap second table TAI_UTC seems out-of-date.
03-Oct-2024 14:30:32	2024-10-03 14:30:33 SEVERE MeasTable::dUTC(Double) (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290)+ Until the table is updated (see the CASA documentation or your system admin),
03-Oct-2024 14:30:32	2024-10-03 14:30:33 SEVERE MeasTable::dUTC(Double) (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290)+ times and coordinates derived from UTC could be wrong by 1s or more.
03-Oct-2024 14:30:32	2024-10-03 14:30:33 INFO partition::::casa Task partition complete. Start time: 2024-10-03 14:30:30.910357 End time: 2024-10-03 14:30:32.685406
03-Oct-2024 14:30:32	2024-10-03 14:30:33 INFO partition::::casa ##### End Task: partition #####
03-Oct-2024 14:30:32	2024-10-03 14:30:33 INFO partition::::casa ##########################################
03-Oct-2024 14:30:32	2024-10-03 14:30:33 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:32	2024-10-03 14:30:33 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:35	2024-10-03 14:30:33 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 )
03-Oct-2024 14:30:35	2024-10-03 14:30:33 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:35	2024-10-03 14:30:33 INFO ParallelDataHelper::::casa Input vis is a Multi-MS
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-1 Caltable mstransform_reference/ngc5921.bcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-1 Caltable mstransform_reference/ngc5921.fluxscale is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-1 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-1 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-2 Caltable mstransform_reference/ngc5921.bcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-2 Caltable mstransform_reference/ngc5921.fluxscale is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-2 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-2 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-3 Caltable mstransform_reference/ngc5921.bcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-3 Caltable mstransform_reference/ngc5921.fluxscale is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-3 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:33 SEVERE Calibrater::::MPIServer-3 Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN . The following callib entry matches no data
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + . in the selected MS (ngc5921.mms.0001.ms) and will be ignored:
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + . 0:
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + MS: obs= scan= fld=0 intent= spw=
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + CT: tinterp=nearest finterp=
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + obsmap=[] scanmap=[] fldmap=[0]
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + spwmap=[] antmap=[]
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN . The following callib entry matches no data
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + . in the selected MS (ngc5921.mms.0000.ms) and will be ignored:
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + . 0:
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + MS: obs= scan= fld=0 intent= spw=
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + CT: tinterp=nearest finterp=
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + obsmap=[] scanmap=[] fldmap=[0]
03-Oct-2024 14:30:35	2024-10-03 14:30:34 WARN + spwmap=[] antmap=[]
03-Oct-2024 14:30:35	2024-10-03 14:30:34 SEVERE MeasTable::dUTC(Double)::MPIServer-3 (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290) Leap second table TAI_UTC seems out-of-date.
03-Oct-2024 14:30:35	2024-10-03 14:30:34 SEVERE MeasTable::dUTC(Double)::MPIServer-3 (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290)+ Until the table is updated (see the CASA documentation or your system admin),
03-Oct-2024 14:30:35	2024-10-03 14:30:34 SEVERE MeasTable::dUTC(Double)::MPIServer-3 (file /source/casa6/casatools/casacore/measures/Measures/MeasTable.cc, line 4290)+ times and coordinates derived from UTC could be wrong by 1s or more.
03-Oct-2024 14:30:35	2024-10-03 14:30:34 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:35	2024-10-03 14:30:34 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.
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:32.690938 End time: 2024-10-03 14:30:34.601368
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:35	2024-10-03 14:30:35 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 )
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO mstransform::::casa Parse docallib parameters
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::parseMsSpecParams Input file name is mstransform_reference/ngc5921.ms
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::parseMsSpecParams Data column is ALL
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::parseMsSpecParams Output file name is mst_otf_calibration.ms
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::parseMsSpecParams Re-index is enabled
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::parseMsSpecParams Tile shape is [0]
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::parseCalParams Calibration is activated
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::colCheckInfo Adding DATA column to output MS from input DATA column
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::colCheckInfo Adding CORRECTED_DATA column to output MS from input virtual CORRECTED_DATA column
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::open Select data
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::createOutputMSStructure Create output MS structure
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::checkFillWeightSpectrum Optional column WEIGHT_SPECTRUM found in input MS will be written to output MS
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO MSTransformManager::generateIterator OTF calibration activated, using calibration record spec to generate iterator
03-Oct-2024 14:30:35	2024-10-03 14:30:35 INFO Calibrater:: Arranging to calibrate MS: /home/casatest/work/test_task_mstransform_mms/mstransform_reference/ngc5921.ms
03-Oct-2024 14:30:35	2024-10-03 14:30:35 SEVERE Calibrater:: Caltable mstransform_reference/ngc5921.bcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:35 SEVERE Calibrater:: Caltable mstransform_reference/ngc5921.fluxscale is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:35 SEVERE Calibrater:: Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:35	2024-10-03 14:30:35 SEVERE Calibrater:: Caltable mstransform_reference/ngc5921.gcal is missing the following fields: scan scanmap
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO OldCalibrater::setcallib2(callib) Arranging to APPLY:
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO . B Jones: table=mstransform_reference/ngc5921.bcal (by cal library) calWt=true
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO . 0:
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + MS: obs= scan= fld= intent= spw=
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + CT: tinterp=nearest finterp=
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + obsmap=[] scanmap=[] fldmap=[]
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + spwmap=[] antmap=[]
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO OldCalibrater::setcallib2(callib) Arranging to APPLY:
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO . G Jones: table=mstransform_reference/ngc5921.fluxscale (by cal library) calWt=true
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO . 0:
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + MS: obs= scan= fld= intent= spw=
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + CT: tinterp=nearest finterp=
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + obsmap=[] scanmap=[] fldmap=[0, 1, 1]
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + spwmap=[] antmap=[]
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO OldCalibrater::setcallib2(callib) Arranging to APPLY:
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO . G Jones: table=mstransform_reference/ngc5921.gcal (by cal library) calWt=true
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO . 0:
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + MS: obs= scan= fld=0 intent= spw=
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + CT: tinterp=nearest finterp=
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + obsmap=[] scanmap=[] fldmap=[0]
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + spwmap=[] antmap=[]
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO . 1:
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + MS: obs= scan= fld=1,2 intent= spw=
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + CT: tinterp=linear finterp=
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + obsmap=[] scanmap=[] fldmap=[1, 1, 1]
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO + spwmap=[] antmap=[]
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO OldCalibrater::setcallib2(callib) The following calibration terms are arranged for apply:
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO OldCalibrater::setcallib2(callib) . B Jones: table=mstransform_reference/ngc5921.bcal (by cal library) calWt=true
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO OldCalibrater::setcallib2(callib) . G Jones: table=mstransform_reference/ngc5921.fluxscale (by cal library) calWt=true
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO OldCalibrater::setcallib2(callib) . G Jones: table=mstransform_reference/ngc5921.gcal (by cal library) calWt=true
03-Oct-2024 14:30:49	2024-10-03 14:30:35 INFO mstransform::::casa Apply the transformations
03-Oct-2024 14:30:49	2024-10-03 14:30:36 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:34.606733 End time: 2024-10-03 14:30:35.918196
03-Oct-2024 14:30:49	2024-10-03 14:30:36 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:49	2024-10-03 14:30:36 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:49	2024-10-03 14:30:42 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 .
03-Oct-2024 14:30:49	2024-10-03 14:30:48 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 .
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column OFFSET
03-Oct-2024 14:30:49	Column OFFSET PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column POSITION
03-Oct-2024 14:30:49	Column POSITION PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TYPE
03-Oct-2024 14:30:49	Column TYPE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column DISH_DIAMETER
03-Oct-2024 14:30:49	Column DISH_DIAMETER PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FLAG_ROW
03-Oct-2024 14:30:49	Column FLAG_ROW PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column MOUNT
03-Oct-2024 14:30:49	Column MOUNT PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column NAME
03-Oct-2024 14:30:49	Column NAME PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column STATION
03-Oct-2024 14:30:49	Column STATION PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FLAG_ROW
03-Oct-2024 14:30:49	Column FLAG_ROW PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column POLARIZATION_ID
03-Oct-2024 14:30:49	Column POLARIZATION_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column SPECTRAL_WINDOW_ID
03-Oct-2024 14:30:49	Column SPECTRAL_WINDOW_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column POSITION
03-Oct-2024 14:30:49	Column POSITION PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column BEAM_OFFSET
03-Oct-2024 14:30:49	Column BEAM_OFFSET PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column POLARIZATION_TYPE
03-Oct-2024 14:30:49	Column POLARIZATION_TYPE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column POL_RESPONSE
03-Oct-2024 14:30:49	Column POL_RESPONSE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column RECEPTOR_ANGLE
03-Oct-2024 14:30:49	Column RECEPTOR_ANGLE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column ANTENNA_ID
03-Oct-2024 14:30:49	Column ANTENNA_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column BEAM_ID
03-Oct-2024 14:30:49	Column BEAM_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FEED_ID
03-Oct-2024 14:30:49	Column FEED_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column INTERVAL
03-Oct-2024 14:30:49	Column INTERVAL PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column NUM_RECEPTORS
03-Oct-2024 14:30:49	Column NUM_RECEPTORS PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column SPECTRAL_WINDOW_ID
03-Oct-2024 14:30:49	Column SPECTRAL_WINDOW_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TIME
03-Oct-2024 14:30:49	Column TIME PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column DELAY_DIR
03-Oct-2024 14:30:49	Column DELAY_DIR PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column PHASE_DIR
03-Oct-2024 14:30:49	Column PHASE_DIR PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column REFERENCE_DIR
03-Oct-2024 14:30:49	Column REFERENCE_DIR PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column CODE
03-Oct-2024 14:30:49	Column CODE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FLAG_ROW
03-Oct-2024 14:30:49	Column FLAG_ROW PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column NAME
03-Oct-2024 14:30:49	Column NAME PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column NUM_POLY
03-Oct-2024 14:30:49	Column NUM_POLY PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column SOURCE_ID
03-Oct-2024 14:30:49	Column SOURCE_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TIME
03-Oct-2024 14:30:49	Column TIME PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column APPLIED
03-Oct-2024 14:30:49	Column APPLIED PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column COMMAND
03-Oct-2024 14:30:49	Column COMMAND PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column INTERVAL
03-Oct-2024 14:30:49	Column INTERVAL PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column LEVEL
03-Oct-2024 14:30:49	Column LEVEL PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column REASON
03-Oct-2024 14:30:49	Column REASON PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column SEVERITY
03-Oct-2024 14:30:49	Column SEVERITY PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TIME
03-Oct-2024 14:30:49	Column TIME PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TYPE
03-Oct-2024 14:30:49	Column TYPE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column DIRECTION
03-Oct-2024 14:30:49	Column DIRECTION PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column ANTENNA_ID
03-Oct-2024 14:30:49	Column ANTENNA_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column INTERVAL
03-Oct-2024 14:30:49	Column INTERVAL PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column NAME
03-Oct-2024 14:30:49	Column NAME PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column NUM_POLY
03-Oct-2024 14:30:49	Column NUM_POLY PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TARGET
03-Oct-2024 14:30:49	Column TARGET PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TIME
03-Oct-2024 14:30:49	Column TIME PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TIME_ORIGIN
03-Oct-2024 14:30:49	Column TIME_ORIGIN PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TRACKING
03-Oct-2024 14:30:49	Column TRACKING PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column CORR_TYPE
03-Oct-2024 14:30:49	Column CORR_TYPE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column CORR_PRODUCT
03-Oct-2024 14:30:49	Column CORR_PRODUCT PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FLAG_ROW
03-Oct-2024 14:30:49	Column FLAG_ROW PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column NUM_CORR
03-Oct-2024 14:30:49	Column NUM_CORR PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FLAG_ROW
03-Oct-2024 14:30:49	Column FLAG_ROW PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column MODE_ID
03-Oct-2024 14:30:49	Column MODE_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TYPE
03-Oct-2024 14:30:49	Column TYPE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TYPE_ID
03-Oct-2024 14:30:49	Column TYPE_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column SUB_TYPE
03-Oct-2024 14:30:49	Column SUB_TYPE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column CAL
03-Oct-2024 14:30:49	Column CAL PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FLAG_ROW
03-Oct-2024 14:30:49	Column FLAG_ROW PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column LOAD
03-Oct-2024 14:30:49	Column LOAD PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column OBS_MODE
03-Oct-2024 14:30:49	Column OBS_MODE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column REF
03-Oct-2024 14:30:49	Column REF PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column SIG
03-Oct-2024 14:30:49	Column SIG PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column SUB_SCAN
03-Oct-2024 14:30:49	Column SUB_SCAN PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TIME_RANGE
03-Oct-2024 14:30:49	Column TIME_RANGE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FLAG_ROW
03-Oct-2024 14:30:49	Column FLAG_ROW PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column OBSERVER
03-Oct-2024 14:30:49	Column OBSERVER PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column PROJECT
03-Oct-2024 14:30:49	Column PROJECT PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column RELEASE_DATE
03-Oct-2024 14:30:49	Column RELEASE_DATE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column SCHEDULE_TYPE
03-Oct-2024 14:30:49	Column SCHEDULE_TYPE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TELESCOPE_NAME
03-Oct-2024 14:30:49	Column TELESCOPE_NAME PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column UVW
03-Oct-2024 14:30:49	Column UVW PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FLAG
03-Oct-2024 14:30:49	Column FLAG PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column WEIGHT
03-Oct-2024 14:30:49	Column WEIGHT PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column ANTENNA1
03-Oct-2024 14:30:49	Column ANTENNA1 PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column ANTENNA2
03-Oct-2024 14:30:49	Column ANTENNA2 PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column ARRAY_ID
03-Oct-2024 14:30:49	Column ARRAY_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column DATA_DESC_ID
03-Oct-2024 14:30:49	Column DATA_DESC_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column EXPOSURE
03-Oct-2024 14:30:49	Column EXPOSURE PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FEED1
03-Oct-2024 14:30:49	Column FEED1 PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FEED2
03-Oct-2024 14:30:49	Column FEED2 PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FIELD_ID
03-Oct-2024 14:30:49	Column FIELD_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column FLAG_ROW
03-Oct-2024 14:30:49	Column FLAG_ROW PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column INTERVAL
03-Oct-2024 14:30:49	Column INTERVAL PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column OBSERVATION_ID
03-Oct-2024 14:30:49	Column OBSERVATION_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column PROCESSOR_ID
03-Oct-2024 14:30:49	Column PROCESSOR_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column SCAN_NUMBER
03-Oct-2024 14:30:49	Column SCAN_NUMBER PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column STATE_ID
03-Oct-2024 14:30:49	Column STATE_ID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TIME
03-Oct-2024 14:30:49	Column TIME PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column TIME_CENTROID
03-Oct-2024 14:30:49	Column TIME_CENTROID PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column DATA
03-Oct-2024 14:30:49	Column DATA PASSED
03-Oct-2024 14:30:49
03-Oct-2024 14:30:49	Testing column CORRECTED_DATA
03-Oct-2024 14:30:49	Column CORRECTED_DATA PASSED
03-Oct-2024 14:30:49	/home/casatest/work/pkg/casa-6.7.0-7-py3.8.el8/lib/py/lib/python3.8/site-packages/casatasks/private/task_mstransform.py:268: ResourceWarning: unclosed file <_io.TextIOWrapper name='mstransform_reference/ngc5921_callib.txt' mode='r' encoding='UTF-8'>
03-Oct-2024 14:30:49	mycallib.read(callib)
03-Oct-2024 14:30:49	ResourceWarning: Enable tracemalloc to get the object allocation traceback
03-Oct-2024 14:30:56	.2024-10-03 14:30:49 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:56	2024-10-03 14:30:49 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:56	2024-10-03 14:30:49 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 )
03-Oct-2024 14:30:56	2024-10-03 14:30:49 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:56	2024-10-03 14:30:52 INFO ParallelDataHelper::::casa Consolidate the sub-tables
03-Oct-2024 14:30:56	2024-10-03 14:30:52 INFO MSTransformDataHandler::mergeSpwSubTables Merging SPECTRAL_WINDOW sub-tables from all sub-MSs to form MMS-level SPECTRAL_WINDOW sub-table
03-Oct-2024 14:30:56	2024-10-03 14:30:52 INFO MSTransformDataHandler::mergeDDISubTables Merging DDI sub-tables from all sub-MSs to form MMS-level DDI sub-table
03-Oct-2024 14:30:56	2024-10-03 14:30:52 INFO MSTransformDataHandler::mergeFeedSubTables Merging FEED sub-tables from all sub-MSs to form MMS-level FEED sub-table
03-Oct-2024 14:30:56	2024-10-03 14:30:52 INFO MSTransformDataHandler::mergeSourceSubTables Merging SOURCE sub-tables from all sub-MSs to form MMS-level SOURCE sub-table
03-Oct-2024 14:30:56	2024-10-03 14:30:52 INFO MSTransformDataHandler::mergeCalDeviceSubtables Merging CALDEVICE sub-tables from all sub-MSs to form MMS-level CALDEVICE sub-table
03-Oct-2024 14:30:56	2024-10-03 14:30:52 INFO MSTransformDataHandler::mergeSysPowerSubtables Merging SYSPOWER sub-tables from all sub-MSs to form MMS-level SYSPOWER sub-table
03-Oct-2024 14:30:56	2024-10-03 14:30:52 INFO MSTransformDataHandler::mergeSyscalSubTables Merging SYSCAL sub-tables from all sub-MSs to form MMS-level SYSCAL sub-table
03-Oct-2024 14:30:56	2024-10-03 14:30:52 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:30:56	2024-10-03 14:30:52 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.
03-Oct-2024 14:30:56	2024-10-03 14:30:53 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:49.059251 End time: 2024-10-03 14:30:52.871268
03-Oct-2024 14:30:56	2024-10-03 14:30:53 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:30:56	2024-10-03 14:30:53 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:56	2024-10-03 14:30:53 INFO flagdata::::casa ##########################################
03-Oct-2024 14:30:56	2024-10-03 14:30:53 INFO flagdata::::casa ##### Begin Task: flagdata #####
03-Oct-2024 14:30:56	2024-10-03 14:30:53 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 )
03-Oct-2024 14:30:56	2024-10-03 14:30:53 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'}
03-Oct-2024 14:30:56	2024-10-03 14:30:53 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'}
03-Oct-2024 14:30:56	2024-10-03 14:30:53 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'}
03-Oct-2024 14:30:56	2024-10-03 14:30:53 INFO flagdata::::casa Task flagdata complete. Start time: 2024-10-03 14:30:52.881125 End time: 2024-10-03 14:30:53.409344
03-Oct-2024 14:30:56	2024-10-03 14:30:53 INFO flagdata::::casa ##### End Task: flagdata #####
03-Oct-2024 14:30:56	2024-10-03 14:30:53 INFO flagdata::::casa ##########################################
03-Oct-2024 14:30:56	.2024-10-03 14:30:53 INFO mstransform::::casa ##########################################
03-Oct-2024 14:30:56	2024-10-03 14:30:53 INFO mstransform::::casa ##### Begin Task: mstransform #####
03-Oct-2024 14:30:56	2024-10-03 14:30:53 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 )
03-Oct-2024 14:30:56	2024-10-03 14:30:54 INFO ParallelDataHelper::::casa Analyzing MS for partitioning
03-Oct-2024 14:30:56	2024-10-03 14:30:57 INFO ParallelDataHelper::::casa Consolidate the sub-tables
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO MSTransformDataHandler::mergeSpwSubTables Merging SPECTRAL_WINDOW sub-tables from all sub-MSs to form MMS-level SPECTRAL_WINDOW sub-table
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO MSTransformDataHandler::mergeDDISubTables Merging DDI sub-tables from all sub-MSs to form MMS-level DDI sub-table
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO MSTransformDataHandler::mergeFeedSubTables Merging FEED sub-tables from all sub-MSs to form MMS-level FEED sub-table
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO MSTransformDataHandler::mergeSourceSubTables Merging SOURCE sub-tables from all sub-MSs to form MMS-level SOURCE sub-table
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO MSTransformDataHandler::mergeCalDeviceSubtables Merging CALDEVICE sub-tables from all sub-MSs to form MMS-level CALDEVICE sub-table
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO MSTransformDataHandler::mergeSysPowerSubtables Merging SYSPOWER sub-tables from all sub-MSs to form MMS-level SYSPOWER sub-table
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO MSTransformDataHandler::mergeSyscalSubTables Merging SYSCAL sub-tables from all sub-MSs to form MMS-level SYSCAL sub-table
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO ParallelDataHelper::::casa Finalizing MMS structure
03-Oct-2024 14:31:15	2024-10-03 14:30:57 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.
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO mstransform::::casa Task mstransform complete. Start time: 2024-10-03 14:30:53.497516 End time: 2024-10-03 14:30:57.311743
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO mstransform::::casa ##### End Task: mstransform #####
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO mstransform::::casa ##########################################
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO flagdata::::casa ##########################################
03-Oct-2024 14:31:15	2024-10-03 14:30:57 INFO flagdata::::casa ##### Begin Task: flagdata #####
03-Oct-2024 14:31:15	2024-10-03 14:30:57 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 )
03-Oct-2024 14:31:15	2024-10-03 14:30:58 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'}
03-Oct-2024 14:31:15	2024-10-03 14:30:58 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'}
03-Oct-2024 14:31:15	2024-10-03 14:30:58 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'}
03-Oct-2024 14:31:15	2024-10-03 14:30:58 INFO flagdata::::casa Task flagdata complete. Start time: 2024-10-03 14:30:57.321098 End time: 2024-10-03 14:30:57.748744
03-Oct-2024 14:31:15	2024-10-03 14:30:58 INFO flagdata::::casa ##### End Task: flagdata #####
03-Oct-2024 14:31:15	2024-10-03 14:30:58 INFO flagdata::::casa ##########################################
03-Oct-2024 14:31:15	.
03-Oct-2024 14:31:15	----------------------------------------------------------------------
03-Oct-2024 14:31:15	Ran 47 tests in 157.277s
03-Oct-2024 14:31:15
03-Oct-2024 14:31:15	OK (skipped=1)
03-Oct-2024 14:31:15	Marking test as passed.
03-Oct-2024 14:31:15	Executor result: 0
03-Oct-2024 14:31:15	Failed tests:[]
03-Oct-2024 14:31:15
03-Oct-2024 14:31:15	Results: [{'testname': 'test_task_sdintimaging', 'runtime': '0:29:23.134192', 'returncode': 0, 'testerr': []}, {'testname': 'test_task_deconvolve', 'runtime': '0:26:33.944975', 'returncode': 0, 'testerr': []}, {'testname': 'test_task_mstransform_mms', 'runtime': '0:02:56.606431', 'returncode': 0, 'testerr': []}]
03-Oct-2024 14:31:15	Stopping Xvfb.
03-Oct-2024 14:31:15	Stopping framebuffer <Popen: returncode: None args: ['Xvfb', ':87', '-screen', '0', '2048x2048x24...>
03-Oct-2024 14:31:15	Virtual frame buffer deployed at :87 with pid 98 successfully shutdown
03-Oct-2024 14:31:15	Xvfb stopped.
03-Oct-2024 14:31:15	total 980416
03-Oct-2024 14:31:15	-rw-r--r-- 1 casaci 15001 1003939900 Oct 3 13:31 casa-6.7.0-7-py3.8.el8.tar.xz
03-Oct-2024 14:31:15	-rw-r--r-- 1 casaci casatest 594 Oct 3 14:31 xUnit-suite-mpi-8.xml
03-Oct-2024 14:31:15	Finished task 'MPI Tasks Tests on EL8' with result: Success
03-Oct-2024 14:31:15	Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
03-Oct-2024 14:31:15	Parsing test results under /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMTC-MTR85...
03-Oct-2024 14:31:15	Finished task 'Parse test results' with result: Success
03-Oct-2024 14:31:15	Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
03-Oct-2024 14:31:15	Beginning to execute external process for build 'CASA - CMake Master Test Casalith - MPI Task Tests Rocky 8.5 Docker #113 (CASA-CMTC-MTR85-113)' ... running command line: /export/cvpost135-nvme/casa/bamboohome/temp/CASA-CMTC-MTR85-113-ScriptBuildTask-5825065043837613320.sh ... in: /export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMTC-MTR85 bamboo_planRepository_1_branch=master bamboo_dependency_parent_0=CASA-CMBC-119 bamboo_jira_components= bamboo_dependency_parent_total=1 bamboo_planRepository_1_branchDisplayName=master bamboo_repository_revision_number=ac417588598185c1f1ca6b64633ab3ecb6390738 bamboo_resultsUrl=https://open-bamboo.nrao.edu/browse/CASA-CMTC-MTR85-113 bamboo_build_working_directory=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMTC-MTR85 bamboo_planRepository_1_name=casa6 bamboo_buildKey=CASA-CMTC-MTR85 bamboo_shortPlanName=CMake Master Test Casalith bamboo_capability_hostname=cvpost135 bamboo_planRepository_name=casa6 bamboo_buildNumber=113 bamboo_repository_84279336_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git bamboo_shortJobName=MPI Task Tests Rocky 8.5 Docker bamboo_buildResultsUrl=https://open-bamboo.nrao.edu/browse/CASA-CMTC-MTR85-113 bamboo_repository_84279336_name=casa6 bamboo_planRepository_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git bamboo_atlutil_user=casaadm bamboo_ManualBuildTriggerReason_stageName=Default Stage bamboo_agentId=81985537 bamboo_planName=CASA - CMake Master Test Casalith bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_7=/export/data_1/bamboohome/temp/CASA-CMTC-MTR85-113-ScriptBuildTask-8739306387089408631.sh bamboo_shortPlanKey=CMTC bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_9=/export/cvpost135-nvme/casa/bamboohome/temp/CASA-CMTC-MTR85-113-ScriptBuildTask-8847712528784622787.sh bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_8=/export/cvpost135-nvme/casa/bamboohome/temp/CASA-CMTC-MTR85-113-ScriptBuildTask-1743199283954918221.sh bamboo_shortJobKey=MTR85 bamboo_planRepository_revision=ac417588598185c1f1ca6b64633ab3ecb6390738 bamboo_buildTimeStamp=2024-10-03T09:30:30.388-04:00 bamboo_repository_previous_revision_number=597480507465b5b0a800ba5dcfe13554a12b4bd2 bamboo_planRepository_previousRevision=597480507465b5b0a800ba5dcfe13554a12b4bd2 bamboo_repository_84279336_git_username= bamboo_build_commandline_com_atlassian_bamboo_plugins_scripttask_task_builder_script_5=/export/cvpost135-nvme/casa/bamboohome/temp/CASA-CMTC-MTR85-113-ScriptBuildTask-3750552269840670044.sh bamboo_repository_git_branch=master bamboo_buildResultKey=CASA-CMTC-MTR85-113 bamboo_repository_branch_name=master bamboo_buildPlanName=CASA - CMake Master Test Casalith - MPI Task Tests Rocky 8.5 Docker bamboo_repository_84279336_branch_name=master bamboo_repository_84279336_git_branch=master bamboo_planRepository_1_revision=ac417588598185c1f1ca6b64633ab3ecb6390738 bamboo_repository_84279336_previous_revision_number=597480507465b5b0a800ba5dcfe13554a12b4bd2 bamboo_repository_name=casa6 bamboo_buildFailed=false bamboo_capability_system_docker_executable=/usr/bin/docker bamboo_planRepository_branch=master bamboo_capability_system_jdk_JDK_1_8_0_361__JRE_=/lustre/cv/sw/casa/casaci/java/jre1.8.0_361 bamboo_agentWorkingDirectory=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir bamboo_jobFailed=false bamboo_capability_system_git_executable=/usr/bin/git bamboo_repository_git_username= bamboo_planRepository_1_previousRevision=597480507465b5b0a800ba5dcfe13554a12b4bd2 bamboo_planRepository_branchDisplayName=master bamboo_planRepository_1_type=bbserver bamboo_planRepository_branchName=master bamboo_shortPlanBranchName= bamboo_capability_system_jdk_JDK=/lustre/cv/sw/casa/casaci/java/jre1.8.0_361 bamboo_atlutil_password=******** bamboo_plan_storageTag=plan-84148320 bamboo_repository_84279336_revision_number=ac417588598185c1f1ca6b64633ab3ecb6390738 bamboo_planRepository_1_username= bamboo_planRepository_type=bbserver bamboo_git_cache_directory=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/_git-repositories-cache bamboo_ManualBuildTriggerReason_userName=vsuorant bamboo_repository_git_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git bamboo_DependencyTriggerReason_triggeringBuildResultKey=CASA-CMBC-119 bamboo_testlist= bamboo_tmp_directory=/export/cvpost135-nvme/casa/bamboohome/temp bamboo_working_directory=/export/cvpost135-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMTC-MTR85 bamboo_planKey=CASA-CMTC bamboo_RerunBuildTriggerReason_noOfRetries=1 bamboo_planRepository_1_repositoryUrl=ssh://git@open-bitbucket.nrao.edu:7999/casa/casa6.git bamboo_planRepository_username= bamboo_capability_system_jdk_JDK_1_8=/lustre/cv/sw/casa/casaci/java/jre1.8.0_361 bamboo_capability_gpu_installed=true bamboo_planRepository_1_branchName=master
03-Oct-2024 14:31:16	Starting cleanup
03-Oct-2024 14:31:26	test-container
03-Oct-2024 14:31:31	test-container
03-Oct-2024 14:31:31	Cleanup complete
03-Oct-2024 14:31:31	Finished task 'Cleanup' with result: Success
03-Oct-2024 14:31:31	Running post build plugin 'Docker Container Cleanup'
03-Oct-2024 14:31:31	Running post build plugin 'NCover Results Collector'
03-Oct-2024 14:31:31	Running post build plugin 'Build Results Label Collector'
03-Oct-2024 14:31:31	Running post build plugin 'Clover Results Collector'
03-Oct-2024 14:31:31	Running post build plugin 'npm Cache Cleanup'
03-Oct-2024 14:31:31	Running post build plugin 'Artifact Copier'
03-Oct-2024 14:31:31	Finalising the build...
03-Oct-2024 14:31:31	Stopping timer.
03-Oct-2024 14:31:31	Build CASA-CMTC-MTR85-113 completed.
03-Oct-2024 10:31:31	Running on server: post build plugin 'Build Hanging Detection Configuration'
03-Oct-2024 10:31:31	Running on server: post build plugin 'NCover Results Collector'
03-Oct-2024 10:31:31	Running on server: post build plugin 'Build Labeller'
03-Oct-2024 10:31:31	Running on server: post build plugin 'Clover Delta Calculator'
03-Oct-2024 10:31:31	Running on server: post build plugin 'Maven Dependencies Postprocessor'
03-Oct-2024 10:31:31	All post build plugins have finished
03-Oct-2024 10:31:31	Generating build results summary...
03-Oct-2024 10:31:31	Saving build results to disk...
03-Oct-2024 10:31:31	Store variable context...
03-Oct-2024 10:31:31	Finished building CASA-CMTC-MTR85-113.

Older logs

Filename	Actions
plan-84148320-MTR85-113.log.1	Download or view

Build: #113 was successful

Job: MPI Task Tests Rocky 8.5 Docker was successful

Stages & jobs

Default Stage

Build log

Older logs

Build #113

Build: #113 was successful

Job: MPI Task Tests Rocky 8.5 Docker was successful

Stages & jobs

Default Stage

Build log

Older logs