Build: #192 failed

Job: Test ALMA SD Stakeholder Multi Target Imaging Many Linux 2.28 cvpost EL8 did not complete

Build log

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

26-Dec-2025 15:09:00 Fetching Repository: casaviewer
26-Dec-2025 15:09:00         Running:  git clone https://open-bitbucket.nrao.edu/scm/casa/casaviewer
26-Dec-2025 15:09:00 Executing: ['git', 'clone', 'https://open-bitbucket.nrao.edu/scm/casa/casaviewer']
26-Dec-2025 15:09:00 Cloning into 'casaviewer'...
26-Dec-2025 15:09:00 Executor result: 0
26-Dec-2025 15:09:00 Failed tests:[]
26-Dec-2025 15:09:00         Tag: tags/2.4.4
26-Dec-2025 15:09:07         Running:  git checkout tags/2.4.4
26-Dec-2025 15:09:07 Executing: ['git', 'checkout', 'tags/2.4.4']
26-Dec-2025 15:09:07 Note: switching to 'tags/2.4.4'.
26-Dec-2025 15:09:07
26-Dec-2025 15:09:07 You are in 'detached HEAD' state. You can look around, make experimental
26-Dec-2025 15:09:07 changes and commit them, and you can discard any commits you make in this
26-Dec-2025 15:09:07 state without impacting any branches by switching back to a branch.
26-Dec-2025 15:09:07
26-Dec-2025 15:09:07 If you want to create a new branch to retain commits you create, you may
26-Dec-2025 15:09:07 do so (now or later) by using -c with the switch command. Example:
26-Dec-2025 15:09:07
26-Dec-2025 15:09:07   git switch -c <new-branch-name>
26-Dec-2025 15:09:07
26-Dec-2025 15:09:07 Or undo this operation with:
26-Dec-2025 15:09:07
26-Dec-2025 15:09:07   git switch -
26-Dec-2025 15:09:07
26-Dec-2025 15:09:07 Turn off this advice by setting config variable advice.detachedHead to false
26-Dec-2025 15:09:07
26-Dec-2025 15:09:07 HEAD is now at e54f84a Pull request #52: CAS-14623
26-Dec-2025 15:09:07 Executor result: 0
26-Dec-2025 15:09:07 Failed tests:[]
26-Dec-2025 15:09:07 Reading config from: /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/casa6/casatestutils/casatestutils/component_to_test_map.json
26-Dec-2025 15:09:07 Test Paths:  ['/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/casasources//casa6/casatests/regression/', '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/casasources//casa6/casatests/stakeholder/', '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/casasources//casa6/casatasks/tests/', '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/casasources//casa6/casatools/tests/', '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/casasources//casampi/src/casampi/tests', '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/casasources//casaplotms/tests/plotms', '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/casasources//casaviewer/tests/tasks']
26-Dec-2025 15:09:07 Test list provided. Filtering tests.
26-Dec-2025 15:09:07 Subset tests:
26-Dec-2025 15:09:07 test_stk_sd_MultiTargetImaging
26-Dec-2025 15:09:07 Running test: test_stk_sd_MultiTargetImaging in Serial mode
26-Dec-2025 15:09:07 Running cmd ['/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/pkg/casa-6.7.3-14-py3.12.el8/bin/casa', '--nogui', '--nologger', '--log2term', '-c', '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/casasources//casa6/casatests/stakeholder/test_stk_sd_MultiTargetImaging.py']in /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/test_stk_sd_MultiTargetImaging
26-Dec-2025 15:09:07 Executing: ['/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/pkg/casa-6.7.3-14-py3.12.el8/bin/casa', '--nogui', '--nologger', '--log2term', '-c', '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/casasources//casa6/casatests/stakeholder/test_stk_sd_MultiTargetImaging.py']
26-Dec-2025 15:09:07 2025-12-26 20:09:02        SEVERE        ::casa        
26-Dec-2025 15:09:07 2025-12-26 20:09:02        SEVERE        ::casa        The expected casa data was not found at measurespath. CASA may still work if the data can be found in datapath.
26-Dec-2025 15:09:07 2025-12-26 20:09:02        SEVERE        ::casa        The expected measures data was not found at measurespath. CASA may still work if the data can be found in datapath.
26-Dec-2025 15:09:07 2025-12-26 20:09:02        SEVERE        ::casa        visit https://casadocs.readthedocs.io/en/stable/notebooks/external-data.html for more information
26-Dec-2025 15:09:07 2025-12-26 20:09:02        SEVERE        ::casa        
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        Using user configuration file /users/casaci/.casa/config.py
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        loaded config file : /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/pkg/casa-6.7.3-14-py3.12.el8/lib/py/lib/python3.12/site-packages/casaconfig/config.py
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        loaded config file : /home/casa/casasiteconfig.py
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        loaded config file : /users/casaci/.casa/config.py
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        config values
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            agg = False
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            cachedir = '/users/casaci/.casa'
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            casaconfig_verbose = 1
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            colors = 'Neutral'
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            data_auto_update = False
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            data_update_interval = 1
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            datapath = ['/export/cvpost134-4tb/casa/casa-data/casatestdata', '/export/cvpost134-4tb/casa/casa-data/casatestdata-large', '/export/cvpost134-4tb/casa/casa-data/casa-data', '/export/cvpost135-4tb/casa/casa-data/casatestdata', '/export/cvpost135-4tb/casa/casa-data/casatestdata-large', '/home/casa/data/master', '/opt/casa/data/casatestdata', '/opt/casa/data/casatestdata-large', '/lustre/cv/sw/casa/casaci/data/casatestdata', '/lustre/cv/sw/casa/casaci/data/casatestdata-large', '/lustre/cv/sw/casa/casaci/pipeline-testdata', '/opt/pltestdata/pipeline-testdata', '/export/cvpost134-4tb/casa/casa-data/casatestdata', '/export/cvpost135-4tb/casa/casa-data/casatestdata', '/export/data_2/casatestdata-large']
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            iplog = False
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            iplogfile = '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/test_stk_sd_MultiTargetImaging/ipython-20251226-200900.log'
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            log2term = True
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            logfile = '/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/test_stk_sd_MultiTargetImaging/casa-20251226-200900.log'
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            measures_auto_update = False
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            measures_site = ['https://www.astron.nl/iers/', 'https://go.nrao.edu/iers/']
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            measures_site_interval = 2
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            measures_update_interval = 1
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            measurespath = '/opt/casa/data/master'
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            nogui = True
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            nologfile = False
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            nologger = True
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            pipeline = False
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            startupfile = '/users/casaci/.casa/startup.py'
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa            user_site = False
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        casarundata version is unknown, this is probably a legacy version not installed by casaconfig
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        measures version is unknown, this is probably a legacy version not installed by casaconfig
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa        Checking Measures tables in data repository sub-directory /export/cvpost134-4tb/casa/casa-data/casa-data/geodetic
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa          IERSeop2000 (version date, last date in table (UTC)): 2025/12/15/15:15, 2025/11/15/00:00:00
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa          IERSeop97 (version date, last date in table (UTC)): 2025/12/15/15:15, 2025/11/15/00:00:00
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa          IERSpredict (version date, last date in table (UTC)): 2025/12/23/15:15, 2026/03/23/00:00:00
26-Dec-2025 15:09:07 2025-12-26 20:09:04        INFO        ::casa          TAI_UTC (version date, last date in table (UTC)): 2025/12/21/15:15, 2017/01/01/00:00:00
26-Dec-2025 15:09:07 2025-12-26 20:09:08        INFO        ::casa        Step 0 Import of the ASDM
26-Dec-2025 15:09:07 2025-12-26 20:09:08        INFO        importasdm::::casa        ##########################################
26-Dec-2025 15:09:07 2025-12-26 20:09:08        INFO        importasdm::::casa        ##### Begin Task: importasdm         #####
26-Dec-2025 15:10:29 2025-12-26 20:09:08        INFO        importasdm::::casa        importasdm( asdm='uid___A002_Xe3a5fd_Xe38e', vis='', createmms=False, separationaxis='auto', numsubms='auto', corr_mode='all', srt='all', time_sampling='all', ocorr_mode='ca', compression=False, lazy=False, asis='Antenna Station Receiver Source CalAtmosphere CalWVR CorrelatorMode SBSummary', wvr_corrected_data='no', scans='', ignore_time=False, process_syspower=True, process_caldevice=False, process_pointing=True, process_flags=True, tbuff=0.0, applyflags=False, savecmds=False, outfile='', flagbackup=True, verbose=False, overwrite=False, bdfflags=True, with_pointing_correction=True, convert_ephem2geo=True, polyephem_tabtimestep=0.0 )
26-Dec-2025 15:10:29 2025-12-26 20:09:57        INFO        importasdm::::casa        No ephemerides attached.
26-Dec-2025 15:10:29 2025-12-26 20:09:57        INFO        AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:10:29 2025-12-26 20:09:57        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called Original
26-Dec-2025 15:10:29 2025-12-26 20:09:59        INFO        importasdm::::casa        Found Flag.xml in SDM
26-Dec-2025 15:10:29 2025-12-26 20:09:59        INFO        importasdm::::casa        Found Antenna.xml in SDM
26-Dec-2025 15:10:29 2025-12-26 20:09:59        INFO        importasdm::::casa        Found SpectralWindow.xml in SDM
26-Dec-2025 15:10:29 2025-12-26 20:09:59        INFO        importasdm::::casa        Found 3 rows in Antenna.xml
26-Dec-2025 15:10:29 2025-12-26 20:09:59        INFO        importasdm::::casa        Found 28 rows in SpectralWindow.xml
26-Dec-2025 15:10:29 2025-12-26 20:09:59        INFO        importasdm::::casa        Will construct spw flags using names
26-Dec-2025 15:10:29 2025-12-26 20:09:59        INFO        importasdm::::casa        Will construct polarization flags using polarizationType
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        importasdm::::casa        Found numAntenna=1 must be a new style SDM
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        importasdm::::casa        Found numSpectralWindow=16 must be a new style SDM
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        importasdm::::casa        Found 1075 flags in Flag.xml
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        importasdm::::casa        Will not apply flags to uid___A002_Xe3a5fd_Xe38e.ms (apply_flags=False), use flagcmd to apply
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        importasdm::::casa        There are 0 rows already in FLAG_CMD
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        importasdm::::casa        Saved 1075 rows to FLAG_CMD of uid___A002_Xe3a5fd_Xe38e.ms
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        importasdm::::casa        Task importasdm complete. Start time: 2025-12-26 15:09:07.868336 End time: 2025-12-26 15:09:59.729971
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        importasdm::::casa        ##### End Task: importasdm           #####
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        importasdm::::casa        ##########################################
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        flagdata::::casa        ##########################################
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        flagdata::::casa        flagdata( vis='uid___A002_Xe3a5fd_Xe38e.ms', 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='after-mystep0-importasdm', action='apply', display='', flagbackup=True, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        flagdata::::casa        Summary mode is active
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        flagdata::::casa        Initializing the agents
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        Summary::setAgentParameters          display is:
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
26-Dec-2025 15:10:29 2025-12-26 20:10:00        INFO        flagdata::::casa        Running the agentflagger tool
26-Dec-2025 15:10:29 2025-12-26 20:10:01        INFO        FlagMSHandler::        Chunk = 1 [progress: 2%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J0529-7245), Spw = 0, Channels = 1, Corrs = [ XX YY ], Total Rows = 45360
26-Dec-2025 15:10:29 2025-12-26 20:10:03        INFO        FlagMSHandler::        Chunk = 28 [progress: 11%], Observation = 0, Array = 0, Scan = 3, Field = 1 (Ridge-W1_OFF_0), Spw = 13, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:10:29 2025-12-26 20:10:06        INFO        FlagMSHandler::        Chunk = 42 [progress: 21%], Observation = 0, Array = 0, Scan = 3, Field = 2 (Ridge-W1), Spw = 14, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:10:29 2025-12-26 20:10:09        INFO        FlagMSHandler::        Chunk = 68 [progress: 30%], Observation = 0, Array = 0, Scan = 5, Field = 3 (Ridge-W2_OFF_0), Spw = 14, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:10:29 2025-12-26 20:10:12        INFO        FlagMSHandler::        Chunk = 82 [progress: 42%], Observation = 0, Array = 0, Scan = 5, Field = 4 (Ridge-W2), Spw = 15, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:10:29 2025-12-26 20:10:15        INFO        FlagMSHandler::        Chunk = 108 [progress: 50%], Observation = 0, Array = 0, Scan = 7, Field = 5 (Ridge-W3_OFF_0), Spw = 15, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:10:29 2025-12-26 20:10:17        INFO        FlagMSHandler::        Chunk = 121 [progress: 60%], Observation = 0, Array = 0, Scan = 7, Field = 6 (Ridge-W3), Spw = 15, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:10:29 2025-12-26 20:10:20        INFO        FlagMSHandler::        Chunk = 148 [progress: 70%], Observation = 0, Array = 0, Scan = 9, Field = 7 (Ridge-W4_OFF_0), Spw = 16, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:10:29 2025-12-26 20:10:23        INFO        FlagMSHandler::        Chunk = 161 [progress: 80%], Observation = 0, Array = 0, Scan = 9, Field = 8 (Ridge-W4), Spw = 16, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:10:29 2025-12-26 20:10:26        INFO        FlagMSHandler::        Chunk = 197 [progress: 91%], Observation = 0, Array = 0, Scan = 11, Field = 10 (Ridge-W5), Spw = 13, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        FlagMSHandler::        Chunk = 221 [progress: 100%], Observation = 0, Array = 0, Scan = 12, Field = 1 (Ridge-W1_OFF_0), Spw = 24, Channels = 1, Corrs = [ XX YY ], Total Rows = 63
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        FlagMSHandler::nextChunk         ====================================================================================
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          observation 0 flagged: 0 total: 1.78815e+08 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 10 flagged: 0 total: 1.3197e+06 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 7 flagged: 0 total: 3.3809e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 2 flagged: 0 total: 1.3197e+06 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 1 flagged: 0 total: 1.85154e+06 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 8 flagged: 0 total: 1.3197e+06 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 5 flagged: 0 total: 3.3809e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 3 flagged: 0 total: 3.38091e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 11 flagged: 0 total: 3.3809e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 9 flagged: 0 total: 3.3809e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 4 flagged: 0 total: 1.3197e+06 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 12 flagged: 0 total: 1.31971e+06 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          scan 6 flagged: 0 total: 1.31971e+06 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          antenna PM01 flagged: 0 total: 5.98526e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          antenna PM04 flagged: 0 total: 5.98526e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          antenna PM02 flagged: 0 total: 5.98526e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W5_OFF_0 flagged: 0 total: 1.30712e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W3 flagged: 0 total: 2.07378e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W4_OFF_0 flagged: 0 total: 1.30712e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W1_OFF_0 flagged: 0 total: 2.09894e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          field J0529-7245 flagged: 0 total: 1.85154e+06 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W1 flagged: 0 total: 2.07379e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W4 flagged: 0 total: 2.07378e+07 (0%)
26-Dec-2025 15:10:29 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W2_OFF_0 flagged: 0 total: 1.30712e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W2 flagged: 0 total: 2.07378e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W5 flagged: 0 total: 2.07378e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          field Ridge-W3_OFF_0 flagged: 0 total: 1.30712e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 23 flagged: 0 total: 3.4603e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 20 flagged: 0 total: 16896 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 18 flagged: 0 total: 16896 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 24 flagged: 0 total: 16896 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 17 flagged: 0 total: 6.9206e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 16 flagged: 0 total: 963072 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 3 flagged: 0 total: 90720 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 13 flagged: 0 total: 963072 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 21 flagged: 0 total: 3.4603e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 4 flagged: 0 total: 30864 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 9 flagged: 0 total: 368640 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 11 flagged: 0 total: 368640 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 0 flagged: 0 total: 90720 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 22 flagged: 0 total: 16896 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 5 flagged: 0 total: 368640 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 8 flagged: 0 total: 2880 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 6 flagged: 0 total: 2880 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 1 flagged: 0 total: 90720 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 12 flagged: 0 total: 2880 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 7 flagged: 0 total: 368640 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 10 flagged: 0 total: 2880 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 2 flagged: 0 total: 90720 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 14 flagged: 0 total: 963072 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 19 flagged: 0 total: 3.4603e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          spw 15 flagged: 0 total: 963072 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          correlation YY flagged: 0 total: 8.93919e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          correlation XX flagged: 0 total: 8.94228e+07 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          array 0 flagged: 0 total: 1.78815e+08 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        Summary::getResult          Total Flagged: 0 Total Counts: 1.78815e+08 (0%)
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        flagdata::::casa        Task flagdata complete. Start time: 2025-12-26 15:09:59.757451 End time: 2025-12-26 15:10:29.612213
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        flagdata::::casa        ##### End Task: flagdata             #####
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        flagdata::::casa        ##########################################
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        flagdata::::casa        after-mystep0-importasdm flags_importasdm
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        flagdata::::casa        Step 1 listobs
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        listobs::::casa        ##########################################
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        listobs::::casa        ##### Begin Task: listobs            #####
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        listobs::::casa        listobs( vis='uid___A002_Xe3a5fd_Xe38e.ms', selectdata=True, spw='', field='', antenna='', uvrange='', timerange='', correlation='', scan='', intent='', feed='', array='', observation='', verbose=True, listfile='uid___A002_Xe3a5fd_Xe38e.ms.listobs', listunfl=False, cachesize=50.0, overwrite=False )
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        listobs::ms::summary        Writing output to file: uid___A002_Xe3a5fd_Xe38e.ms.listobs
26-Dec-2025 15:10:39 2025-12-26 20:10:30        INFO        listobs::MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        listobs::::casa        Task listobs complete. Start time: 2025-12-26 15:10:29.622590 End time: 2025-12-26 15:10:30.701997
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        listobs::::casa        ##### End Task: listobs              #####
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        listobs::::casa        ##########################################
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        listobs::::casa        Step 2 A priori flagging
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        ##########################################
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        ##### Begin Task: flagcmd            #####
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        flagcmd( vis='uid___A002_Xe3a5fd_Xe38e.ms', inpmode='table', inpfile='', tablerows=[], reason='any', useapplied=True, tbuff=0.0, ants='', action='plot', flagbackup=True, clearall=False, rowlist=[], plotfile='uid___A002_Xe3a5fd_Xe38e.ms.flagcmd.png', savepars=False, outfile='', overwrite=True )
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        MS spans timerange 2 to 2019/11/19/07:24:43.200
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        Reading from FLAG_CMD table
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        Read 1075 lines from input
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        Executing action = plot
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        Warning: will only reliably plot individual per-antenna flags
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        Found 5 reasons to plot for 3 antennas
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        Found 1075 total flag ranges to plot
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        Found flag times from 2019/11/19/06:30:34.921 to 2019/11/19/07:25:27.129
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        WARNING: Trimming flag times to data limits 2019/11/19/06:31:33.984 to 2019/11/19/07:24:43.200
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        WARNING: Trimming dropped 24 flags entirely
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        Will plot 5 reasons in legend
26-Dec-2025 15:10:39 2025-12-26 20:10:31        INFO        flagcmd::::casa        Plotted 1051 flags
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        Task flagcmd complete. Start time: 2025-12-26 15:10:30.709278 End time: 2025-12-26 15:10:31.805089
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        ##### End Task: flagcmd              #####
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        ##########################################
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        ##########################################
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        ##### Begin Task: flagcmd            #####
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        flagcmd( vis='uid___A002_Xe3a5fd_Xe38e.ms', inpmode='table', inpfile='', tablerows=[], reason='any', useapplied=True, tbuff=0.0, ants='', action='apply', flagbackup=True, clearall=False, rowlist=[], plotfile='', savepars=False, outfile='', overwrite=True )
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        MS spans timerange 2 to 2019/11/19/07:24:43.200
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        Reading from FLAG_CMD table
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        Read 1075 lines from input
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        Executing action = apply
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        Parse the parameters for the agents
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        flagcmd::::casa        Reduced 1075 timerange flags into 12 compound flags
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        AgentFlagger::initAgents        There are initially 12 agents in the list. Agents: manual, manual, manual, manual, manual, manual, manual, manual, manual, manual, ... (2 more, not shown)
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        AgentFlagger::initAgents        There are 12 valid agents in the list. Agents: manual, manual, manual, manual, manual, manual, manual, manual, manual, manual, ... (2 more, not shown)
26-Dec-2025 15:10:39 2025-12-26 20:10:32        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called flagcmd_1
26-Dec-2025 15:10:39 2025-12-26 20:10:36        INFO        FlagMSHandler::        Chunk = 1 [progress: 2%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J0529-7245), Spw = 0, Channels = 1, Corrs = [ XX YY ], Total Rows = 45360
26-Dec-2025 15:10:39 2025-12-26 20:10:39        INFO        FlagMSHandler::        Chunk = 28 [progress: 11%], Observation = 0, Array = 0, Scan = 3, Field = 1 (Ridge-W1_OFF_0), Spw = 13, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:11:05 2025-12-26 20:10:39        INFO        Manual_0::chunkSummary        => Data flagged so far 0.0129449% (420/3244524)
26-Dec-2025 15:11:05 2025-12-26 20:10:39        INFO        Manual_2::chunkSummary        => Data flagged so far 0.0128216% (416/3244524)
26-Dec-2025 15:11:05 2025-12-26 20:10:39        INFO        Manual_6::chunkSummary        => Data flagged so far 0.0129449% (420/3244524)
26-Dec-2025 15:11:05 2025-12-26 20:10:39        INFO        Manual_72::chunkSummary        => Data flagged so far 0.00172599% (56/3244524)
26-Dec-2025 15:11:05 2025-12-26 20:10:39        INFO        Manual_73::chunkSummary        => Data flagged so far 0.0016027% (52/3244524)
26-Dec-2025 15:11:05 2025-12-26 20:10:39        INFO        Manual_74::chunkSummary        => Data flagged so far 0.00172599% (56/3244524)
26-Dec-2025 15:11:05 2025-12-26 20:10:41        INFO        FlagMSHandler::        Chunk = 42 [progress: 21%], Observation = 0, Array = 0, Scan = 3, Field = 2 (Ridge-W1), Spw = 14, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:11:05 2025-12-26 20:10:42        INFO        Manual_72::chunkSummary        => Data flagged so far 0.00148182% (196/13226952)
26-Dec-2025 15:11:05 2025-12-26 20:10:42        INFO        Manual_73::chunkSummary        => Data flagged so far 0.00148182% (196/13226952)
26-Dec-2025 15:11:05 2025-12-26 20:10:42        INFO        Manual_74::chunkSummary        => Data flagged so far 0.00148182% (196/13226952)
26-Dec-2025 15:11:05 2025-12-26 20:10:44        INFO        FlagMSHandler::        Chunk = 68 [progress: 30%], Observation = 0, Array = 0, Scan = 5, Field = 3 (Ridge-W2_OFF_0), Spw = 14, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:11:05 2025-12-26 20:10:45        INFO        Manual_72::chunkSummary        => Data flagged so far 0.00478771% (1052/21972924)
26-Dec-2025 15:11:05 2025-12-26 20:10:45        INFO        Manual_73::chunkSummary        => Data flagged so far 0.00726348% (1596/21972924)
26-Dec-2025 15:11:05 2025-12-26 20:10:45        INFO        Manual_74::chunkSummary        => Data flagged so far 0.00478771% (1052/21972924)
26-Dec-2025 15:11:05 2025-12-26 20:10:47        INFO        FlagMSHandler::        Chunk = 82 [progress: 42%], Observation = 0, Array = 0, Scan = 5, Field = 4 (Ridge-W2), Spw = 15, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:11:05 2025-12-26 20:10:48        INFO        Manual_72::chunkSummary        => Data flagged so far 0.0163695% (2172/13268604)
26-Dec-2025 15:11:05 2025-12-26 20:10:48        INFO        Manual_73::chunkSummary        => Data flagged so far 0.329078% (43664/13268604)
26-Dec-2025 15:11:05 2025-12-26 20:10:48        INFO        Manual_74::chunkSummary        => Data flagged so far 0.0156912% (2082/13268604)
26-Dec-2025 15:11:05 2025-12-26 20:10:50        INFO        FlagMSHandler::        Chunk = 108 [progress: 50%], Observation = 0, Array = 0, Scan = 7, Field = 5 (Ridge-W3_OFF_0), Spw = 15, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:11:05 2025-12-26 20:10:50        INFO        Manual_72::chunkSummary        => Data flagged so far 0.00823484% (1806/21931212)
26-Dec-2025 15:11:05 2025-12-26 20:10:50        INFO        Manual_73::chunkSummary        => Data flagged so far 0.010077% (2210/21931212)
26-Dec-2025 15:11:05 2025-12-26 20:10:50        INFO        Manual_74::chunkSummary        => Data flagged so far 0.00809805% (1776/21931212)
26-Dec-2025 15:11:05 2025-12-26 20:10:52        INFO        FlagMSHandler::        Chunk = 121 [progress: 60%], Observation = 0, Array = 0, Scan = 7, Field = 6 (Ridge-W3), Spw = 15, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:11:05 2025-12-26 20:10:53        INFO        Manual_72::chunkSummary        => Data flagged so far 0.0133359% (1760/13197468)
26-Dec-2025 15:11:05 2025-12-26 20:10:53        INFO        Manual_73::chunkSummary        => Data flagged so far 0.0140027% (1848/13197468)
26-Dec-2025 15:11:05 2025-12-26 20:10:53        INFO        Manual_74::chunkSummary        => Data flagged so far 0.0123357% (1628/13197468)
26-Dec-2025 15:11:05 2025-12-26 20:10:55        INFO        FlagMSHandler::        Chunk = 148 [progress: 70%], Observation = 0, Array = 0, Scan = 9, Field = 7 (Ridge-W4_OFF_0), Spw = 16, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:11:05 2025-12-26 20:10:56        INFO        Manual_72::chunkSummary        => Data flagged so far 0.00871726% (1918/22002336)
26-Dec-2025 15:11:05 2025-12-26 20:10:56        INFO        Manual_73::chunkSummary        => Data flagged so far 0.0147166% (3238/22002336)
26-Dec-2025 15:11:05 2025-12-26 20:10:56        INFO        Manual_74::chunkSummary        => Data flagged so far 0.00855364% (1882/22002336)
26-Dec-2025 15:11:05 2025-12-26 20:10:58        INFO        FlagMSHandler::        Chunk = 161 [progress: 80%], Observation = 0, Array = 0, Scan = 9, Field = 8 (Ridge-W4), Spw = 16, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:11:05 2025-12-26 20:10:59        INFO        Manual_72::chunkSummary        => Data flagged so far 0.0107861% (1428/13239204)
26-Dec-2025 15:11:05 2025-12-26 20:10:59        INFO        Manual_73::chunkSummary        => Data flagged so far 0.0180676% (2392/13239204)
26-Dec-2025 15:11:05 2025-12-26 20:10:59        INFO        Manual_74::chunkSummary        => Data flagged so far 0.0107861% (1428/13239204)
26-Dec-2025 15:11:05 2025-12-26 20:11:01        INFO        FlagMSHandler::        Chunk = 197 [progress: 91%], Observation = 0, Array = 0, Scan = 11, Field = 10 (Ridge-W5), Spw = 13, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:11:05 2025-12-26 20:11:02        INFO        Manual_72::chunkSummary        => Data flagged so far 0.00530036% (1844/34790100)
26-Dec-2025 15:11:05 2025-12-26 20:11:02        INFO        Manual_73::chunkSummary        => Data flagged so far 0.185237% (64444/34790100)
26-Dec-2025 15:11:05 2025-12-26 20:11:02        INFO        Manual_74::chunkSummary        => Data flagged so far 0.00529461% (1842/34790100)
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        FlagMSHandler::        Chunk = 221 [progress: 100%], Observation = 0, Array = 0, Scan = 12, Field = 1 (Ridge-W1_OFF_0), Spw = 24, Channels = 1, Corrs = [ XX YY ], Total Rows = 63
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Manual_72::chunkSummary        => Data flagged so far 0.00490397% (1076/21941412)
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Manual_73::chunkSummary        => Data flagged so far 0.00506804% (1112/21941412)
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Manual_74::chunkSummary        => Data flagged so far 0.00487662% (1070/21941412)
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        FlagMSHandler::nextChunk         ====================================================================================
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Manual_0::tableSummary        => Percentage of data flagged in table selection: 0.00023488%
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Manual_2::tableSummary        => Percentage of data flagged in table selection: 0.000232643%
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Manual_6::tableSummary        => Percentage of data flagged in table selection: 0.00023488%
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Manual_72::tableSummary        => Percentage of data flagged in table selection: 0.00744234%
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Manual_73::tableSummary        => Percentage of data flagged in table selection: 0.0675291%
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Manual_74::tableSummary        => Percentage of data flagged in table selection: 0.00727681%
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        AgentFlagger::run        => Writing flags to the MS
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        flagcmd::::casa        Updated 1075 rows of FLAG_CMD table in MS
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        flagcmd::::casa        Task flagcmd complete. Start time: 2025-12-26 15:10:31.806166 End time: 2025-12-26 15:11:04.083084
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        flagcmd::::casa        ##### End Task: flagcmd              #####
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        flagcmd::::casa        ##########################################
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        flagcmd::::casa        Step 3 Generation of the Tsys cal table
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        gencal::::casa        ##########################################
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        gencal::::casa        ##### Begin Task: gencal             #####
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        gencal::::casa        gencal( vis='uid___A002_Xe3a5fd_Xe38e.ms', caltable='uid___A002_Xe3a5fd_Xe38e.ms.tsys', caltype='tsys', infile='', endpoint='asdm', timeout=180, retry=3, retry_wait_time=5, ant_pos_time_limit=0, spw='', antenna='', pol='', parameter=[], uniform=True )
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        calibrater::open        Opening MS: uid___A002_Xe3a5fd_Xe38e.ms for calibration.
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        Calibrater::        Initializing nominal selection to the whole MS.
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:11:05 2025-12-26 20:11:04        INFO                Creating B TSYS table from MS SYSCAL subtable.
26-Dec-2025 15:11:05 2025-12-26 20:11:05        INFO                Tsys spectra counts per spw for antenna Ids 0-2 (per pol):
26-Dec-2025 15:11:05 2025-12-26 20:11:05        INFO                Spw 17: [6, 6, 6] (=18 spectra; 4096 chans per spectra, per pol)
26-Dec-2025 15:11:05 2025-12-26 20:11:05        INFO                Spw 19: [6, 6, 6] (=18 spectra; 2048 chans per spectra, per pol)
26-Dec-2025 15:11:05 2025-12-26 20:11:05        INFO                Spw 21: [6, 6, 6] (=18 spectra; 2048 chans per spectra, per pol)
26-Dec-2025 15:11:05 2025-12-26 20:11:05        INFO                Spw 23: [6, 6, 6] (=18 spectra; 2048 chans per spectra, per pol)
26-Dec-2025 15:11:05 2025-12-26 20:11:05        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.tsys
26-Dec-2025 15:11:05 2025-12-26 20:11:06        INFO        gencal::::casa        Task gencal complete. Start time: 2025-12-26 15:11:04.093146 End time: 2025-12-26 15:11:05.505051
26-Dec-2025 15:11:05 2025-12-26 20:11:06        INFO        gencal::::casa        ##### End Task: gencal               #####
26-Dec-2025 15:11:05 2025-12-26 20:11:06        INFO        gencal::::casa        ##########################################
26-Dec-2025 15:11:05 2025-12-26 20:11:06        INFO        plotbandpass::::casa        ##########################################
26-Dec-2025 15:11:05 2025-12-26 20:11:06        INFO        plotbandpass::::casa        ##### Begin Task: plotbandpass       #####
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        plotbandpass( caltable='uid___A002_Xe3a5fd_Xe38e.ms.tsys', antenna='', field='', spw='', yaxis='amp', xaxis='freq', figfile='uid___A002_Xe3a5fd_Xe38e.ms.tsys.plots.overlayTime/uid___A002_Xe3a5fd_Xe38e.ms.tsys', plotrange=[0.0, 0.0, 0.0, 0.0], caltable2='', overlay='time', showflagged=False, timeranges='', buildpdf=False, caltable3='', markersize=3, density=108, interactive=False, showpoints='auto', showlines='auto', subplot=22, zoom='', poln=[''], showatm=True, pwv='auto', gs='gs', convert='convert', chanrange='92.1875%', solutionTimeThresholdSeconds=30.0, debug=False, phase='', vis='', showtsky=False, showfdm=True, showatmfield='', lo1='', showimage=False, showatmpoints=False, parentms='', pdftk='pdftk', channeldiff=False, edge=8, resample=1, platformingThreshold=10.0, platformingSigma=10.0, basebands='', showBasebandNumber=False, scans='', figfileSequential=False, chanrangeSetXrange=False )
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        task plotbandpass version number:        2.20.0
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        tracking au version:                        Id: plotbandpass3.py,v 2.20 2024/09/04 16:00:03 thunter Exp
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on uid___A002_Xe3a5fd_Xe38e.ms...
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Available antennas = ['PM01', 'PM02', 'PM04']
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Fields in ms  = ['J0529-7245', 'Ridge-W1_OFF_0', 'Ridge-W1', 'Ridge-W2_OFF_0', 'Ridge-W2', 'Ridge-W3_OFF_0', 'Ridge-W3', 'Ridge-W4_OFF_0', 'Ridge-W4', 'Ridge-W5_OFF_0', 'Ridge-W5']
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Found solutions with 6 unique times across all spws and fields (within a threshold of 1.0 second).
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Median difference between solution times = 564.576890 sec
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Found solutions with 6 unique times (within a threshold of 30 seconds).
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Found solutions with 6 unique scan numbers [ 2  4  6  8 10 12]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        [[06:35, 06:46, 06:56, 07:05, 07:14, 07:24]],
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        4 spws in the solution = [17 19 21 23]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        1 field(s) in the solution = [1] = ['Ridge-W1_OFF_0']
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        This is a B TSYS solution.
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Will overlay solutions from different times
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        originalSpwsToPlot: [17 19 21 23]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        6 timerangeListTimes (after filtering for spw) = [5.08086215e+09 5.08086280e+09 5.08086336e+09 5.08086392e+09
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa+         5.08086449e+09 5.08086505e+09]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        scansToPlot (after filtering for spw) = [ 2  4  6  8 10 12]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        scans to plot: [ 2  4  6  8 10 12]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        UT times to plot: 06:35:49 06:46:38 06:56:01 07:05:25 07:14:49 07:24:14
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Corresponding time IDs (0-based): range(0, 6)
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        antlist = []
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        antennasToPlot = [0 1 2]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        a list of field numbers was given
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        fieldlist = []
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        fieldsToPlot = [1]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        spws to plot (sorted) = [np.int64(17), np.int64(19), np.int64(21), np.int64(23)]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Field IDs to plot: [1]
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        plotbandpass::::casa        Polarizations to plot = ['XX', 'YY']
26-Dec-2025 15:11:44 2025-12-26 20:11:06        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
26-Dec-2025 15:11:44 2025-12-26 20:11:09        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/6 time matches for spw17 field0
26-Dec-2025 15:11:44 2025-12-26 20:11:14        INFO        plotbandpass::::casa        Making directory = uid___A002_Xe3a5fd_Xe38e.ms.tsys.plots.overlayTime/
26-Dec-2025 15:11:44 2025-12-26 20:11:14        INFO        plotbandpass::::casa        Building uid___A002_Xe3a5fd_Xe38e.ms.tsys.plots.overlayTime/uid___A002_Xe3a5fd_Xe38e.ms.tsys.PM01.spw17.png
26-Dec-2025 15:11:44 2025-12-26 20:11:16        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/6 time matches for spw17 field0
26-Dec-2025 15:11:44 2025-12-26 20:11:22        INFO        plotbandpass::::casa        Building uid___A002_Xe3a5fd_Xe38e.ms.tsys.plots.overlayTime/uid___A002_Xe3a5fd_Xe38e.ms.tsys.PM02.spw17.png
26-Dec-2025 15:11:44 2025-12-26 20:11:24        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/6 time matches for spw17 field0
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        plotbandpass::::casa        Building uid___A002_Xe3a5fd_Xe38e.ms.tsys.plots.overlayTime/uid___A002_Xe3a5fd_Xe38e.ms.tsys.PM04.spw17.png
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        plotbandpass::::casa        Task plotbandpass complete. Start time: 2025-12-26 15:11:05.521343 End time: 2025-12-26 15:11:30.262999
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        plotbandpass::::casa        ##### End Task: plotbandpass         #####
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        plotbandpass::::casa        ##########################################
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        plotbandpass::::casa        Step 4 Generation of the Sky cal table
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        sdcal::::casa        ##########################################
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        sdcal::::casa        ##### Begin Task: sdcal              #####
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        sdcal::::casa        sdcal( infile='uid___A002_Xe3a5fd_Xe38e.ms', calmode='ps', fraction='10%', noff=-1, width=0.5, elongated=False, applytable='', interp='', spwmap={}, outfile='uid___A002_Xe3a5fd_Xe38e.ms.sky', overwrite=False, field='', spw='', scan='', **kwargs={} )
26-Dec-2025 15:11:44 2025-12-26 20:11:30        WARN        sdcal::calibrater::setvi(bool,bool)        Forcing use of OLD VisibilityIterator.
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        sdcal::calibrater::open        ****Using OLD VI-driven calibrater tool****
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        sdcal::calibrater::open        Opening MS: uid___A002_Xe3a5fd_Xe38e.ms for calibration.
26-Dec-2025 15:11:44 2025-12-26 20:11:30        INFO        sdcal::Calibrater::        Initializing nominal selection to the whole MS.
26-Dec-2025 15:11:44 2025-12-26 20:11:31        INFO        sdcal::calibrater::setdata        Beginning selectvis--(MSSelection version)-------
26-Dec-2025 15:11:44 2025-12-26 20:11:31        INFO        sdcal::calibrater::reset        Reseting solve/apply state
26-Dec-2025 15:11:44 2025-12-26 20:11:31        INFO        sdcal::Calibrater::selectvis        Performing selection on MeasurementSet
26-Dec-2025 15:11:44 2025-12-26 20:11:31        INFO        sdcal::Calibrater::selectvis        Selection did not drop any rows
26-Dec-2025 15:11:44 2025-12-26 20:11:32        INFO        sdcal::Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
26-Dec-2025 15:11:44 2025-12-26 20:11:32        INFO        sdcal::calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
26-Dec-2025 15:11:44 2025-12-26 20:11:32        INFO        sdcal::calibrater::setdata        Beginning selectvis--(MSSelection version)-------
26-Dec-2025 15:11:44 2025-12-26 20:11:32        INFO        sdcal::calibrater::reset        Reseting solve/apply state
26-Dec-2025 15:11:44 2025-12-26 20:11:32        INFO        sdcal::Calibrater::selectvis        Performing selection on MeasurementSet
26-Dec-2025 15:11:44 2025-12-26 20:11:32        INFO        sdcal::Calibrater::selectvis        Selection did not drop any rows
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::Calibrater::selectvis        Frequency selection: Selecting all channels in all spws.
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::calibrater::setsolve        Beginning setsolve--(MSSelection version)-------
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::Calibrater::setsolve        Arranging to SOLVE:
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::Calibrater::setsolve        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::calibrater::solve        Beginning solve-----------------------------
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::Calibrater::solve        The following calibration terms are arranged for apply:
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::Calibrater::solve        .   (None)
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::Calibrater::solve        The following calibration term is arranged for solve:
26-Dec-2025 15:11:44 2025-12-26 20:11:33        INFO        sdcal::Calibrater::solve        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky append=false solint=inf,none refantmode='flex' refant='none' minsnr=0 apmode=AP solnorm=false
26-Dec-2025 15:11:44 2025-12-26 20:11:36        INFO        sdcal::SingleDishSkyCal::selfGatherAndSolve        Selected:    724,908 rows of reference data
26-Dec-2025 15:11:44 2025-12-26 20:11:36        INFO        sdcal::SingleDishSkyCal::selfGatherAndSolve+        out of  :  2,194,404 rows of user-selected data
26-Dec-2025 15:11:44 2025-12-26 20:11:44        INFO        sdcal::::        Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.sky
26-Dec-2025 15:11:44 2025-12-26 20:11:44        INFO        sdcal::calibrater::solve        Finished solving.
26-Dec-2025 15:11:44 2025-12-26 20:11:44        INFO        sdcal::::casa        Task sdcal complete. Start time: 2025-12-26 15:11:30.274969 End time: 2025-12-26 15:11:44.262663
26-Dec-2025 15:11:44 2025-12-26 20:11:44        INFO        sdcal::::casa        ##### End Task: sdcal                #####
26-Dec-2025 15:11:44 2025-12-26 20:11:44        INFO        sdcal::::casa        ##########################################
26-Dec-2025 15:11:44 2025-12-26 20:11:44        INFO        plotbandpass::::casa        ##########################################
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        ##### Begin Task: plotbandpass       #####
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        plotbandpass( caltable='uid___A002_Xe3a5fd_Xe38e.ms.sky', antenna='', field='', spw='', yaxis='amp', xaxis='freq', figfile='uid___A002_Xe3a5fd_Xe38e.ms.sky.plots.overlayTime/uid___A002_Xe3a5fd_Xe38e.ms.sky', plotrange=[0.0, 0.0, 0.0, 0.0], caltable2='', overlay='time', showflagged=False, timeranges='', buildpdf=False, caltable3='', markersize=3, density=108, interactive=False, showpoints='auto', showlines='auto', subplot=22, zoom='', poln=[''], showatm=True, pwv='auto', gs='gs', convert='convert', chanrange='92.1875%', solutionTimeThresholdSeconds=30.0, debug=False, phase='', vis='', showtsky=False, showfdm=True, showatmfield='', lo1='', showimage=False, showatmpoints=False, parentms='', pdftk='pdftk', channeldiff=False, edge=8, resample=1, platformingThreshold=10.0, platformingSigma=10.0, basebands='', showBasebandNumber=False, scans='', figfileSequential=False, chanrangeSetXrange=False )
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        task plotbandpass version number:        2.20.0
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        tracking au version:                        Id: plotbandpass3.py,v 2.20 2024/09/04 16:00:03 thunter Exp
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on uid___A002_Xe3a5fd_Xe38e.ms...
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        Available antennas = ['PM01', 'PM02', 'PM04']
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        Fields in ms  = ['J0529-7245', 'Ridge-W1_OFF_0', 'Ridge-W1', 'Ridge-W2_OFF_0', 'Ridge-W2', 'Ridge-W3_OFF_0', 'Ridge-W3', 'Ridge-W4_OFF_0', 'Ridge-W4', 'Ridge-W5_OFF_0', 'Ridge-W5']
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        Found solutions with 80 unique times across all spws and fields (within a threshold of 1.0 second).
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        Median difference between solution times = 33.456000 sec
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        Found solutions with 80 unique times (within a threshold of 30 seconds).
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        Found solutions with 5 unique scan numbers [ 3  5  7  9 11]
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        [[06:37, 06:38, 06:39, 06:39, 06:40, 06:40, 06:41, 06:41, 06:42, 06:43, 06:43, 06:44, 06:44, 06:45, 06:45, 06:46, 06:47, 06:48, 06:48, 06:49, 06:49, 06:50, 06:50, 06:51, 06:51, 06:52, 06:53, 06:53, 06:54, 06:54, 06:55, 06:55, 06:56, 06:57, 06:57, 06:58, 06:59, 06:59, 07:00, 07:00, 07:01, 07:01, 07:02, 07:02, 07:03, 07:04, 07:04, 07:05, 07:06, 07:06, 07:07, 07:07, 07:08, 07:09, 07:09, 07:10, 07:10, 07:11, 07:11, 07:12, 07:12, 07:13, 07:14, 07:14, 07:15, 07:16, 07:16, 07:17, 07:17, 07:18, 07:19, 07:19, 07:20, 07:20, 07:21, 07:21, 07:22, 07:22, 07:23, 07:24]],
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        12 spws in the solution = [13 14 15 16 17 18 19 20 21 22 23 24]
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        5 field(s) in the solution = [1 3 5 7 9] = ['Ridge-W1_OFF_0' 'Ridge-W2_OFF_0' 'Ridge-W3_OFF_0' 'Ridge-W4_OFF_0'
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         'Ridge-W5_OFF_0']
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        This is a SDSKY_PS solution.
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        Will overlay solutions from different times
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        originalSpwsToPlot: [13 14 15 16 17 18 19 20 21 22 23 24]
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        80 timerangeListTimes (after filtering for spw) = [5.08086227e+09 5.08086231e+09 5.08086234e+09 5.08086238e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086241e+09 5.08086244e+09 5.08086248e+09 5.08086251e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086255e+09 5.08086258e+09 5.08086262e+09 5.08086265e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086268e+09 5.08086272e+09 5.08086275e+09 5.08086279e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086285e+09 5.08086288e+09 5.08086291e+09 5.08086295e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086298e+09 5.08086301e+09 5.08086305e+09 5.08086308e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086312e+09 5.08086315e+09 5.08086318e+09 5.08086322e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086325e+09 5.08086328e+09 5.08086332e+09 5.08086335e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086341e+09 5.08086344e+09 5.08086348e+09 5.08086351e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086355e+09 5.08086358e+09 5.08086361e+09 5.08086365e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086368e+09 5.08086371e+09 5.08086375e+09 5.08086378e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086381e+09 5.08086385e+09 5.08086388e+09 5.08086391e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086397e+09 5.08086401e+09 5.08086404e+09 5.08086408e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086411e+09 5.08086414e+09 5.08086418e+09 5.08086421e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086424e+09 5.08086428e+09 5.08086431e+09 5.08086434e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086438e+09 5.08086441e+09 5.08086444e+09 5.08086448e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086454e+09 5.08086457e+09 5.08086461e+09 5.08086464e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086467e+09 5.08086471e+09 5.08086474e+09 5.08086477e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086481e+09 5.08086484e+09 5.08086487e+09 5.08086491e+09
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa+         5.08086494e+09 5.08086497e+09 5.08086501e+09 5.08086504e+09]
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        scansToPlot (after filtering for spw) = [ 3  5  7  9 11]
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        scans to plot: [ 3  5  7  9 11]
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        UT times to plot: 06:37:55 06:38:29 06:39:03 06:39:37 06:40:11 06:40:45 06:41:19 06:41:53 06:42:27 06:43:01 06:43:35 06:44:10 06:44:44 06:45:18 06:45:52 06:46:26 06:47:28 06:48:01 06:48:35 06:49:08 06:49:42 06:50:15 06:50:48 06:51:22 06:51:55 06:52:29 06:53:02 06:53:35 06:54:09 06:54:42 06:55:16 06:55:49 06:56:51 06:57:25 06:57:58 06:58:32 06:59:05 06:59:38 07:00:12 07:00:45 07:01:19 07:01:52 07:02:26 07:02:59 07:03:32 07:04:06 07:04:39 07:05:13 07:06:15 07:06:48 07:07:22 07:07:55 07:08:29 07:09:02 07:09:36 07:10:09 07:10:43 07:11:16 07:11:50 07:12:23 07:12:57 07:13:30 07:14:04 07:14:37 07:15:39 07:16:13 07:16:46 07:17:20 07:17:53 07:18:27 07:19:00 07:19:34 07:20:07 07:20:41 07:21:14 07:21:48 07:22:21 07:22:55 07:23:28 07:24:02
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        Corresponding time IDs (0-based): range(0, 80)
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        antlist = []
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        antennasToPlot = [0 1 2]
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        a list of field numbers was given
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        fieldlist = []
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        fieldsToPlot = [1 3 5 7 9]
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        spws to plot (sorted) = [np.int64(13), np.int64(14), np.int64(15), np.int64(16), np.int64(17), np.int64(18), np.int64(19), np.int64(20), np.int64(21), np.int64(22), np.int64(23), np.int64(24)]
26-Dec-2025 15:11:48 2025-12-26 20:11:44        INFO        plotbandpass::::casa        Field IDs to plot: [1 3 5 7 9]
26-Dec-2025 15:11:48 2025-12-26 20:11:45        INFO        plotbandpass::::casa        Polarizations to plot = ['XX', 'YY']
26-Dec-2025 15:11:48 2025-12-26 20:11:45        INFO        plotbandpass::::casa        Skipping spw=13 because it has only 1 channel.
26-Dec-2025 15:11:48 2025-12-26 20:11:45        INFO        plotbandpass::::casa        Skipping spw=14 because it has only 1 channel.
26-Dec-2025 15:11:48 2025-12-26 20:11:45        INFO        plotbandpass::::casa        Skipping spw=15 because it has only 1 channel.
26-Dec-2025 15:11:48 2025-12-26 20:11:45        INFO        plotbandpass::::casa        Skipping spw=16 because it has only 1 channel.
26-Dec-2025 15:11:48 2025-12-26 20:11:45        INFO        MSMetaData::_computeScanAndSubScanProperties         Computing scan and subscan properties...
26-Dec-2025 15:11:48 2025-12-26 20:11:49        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field0
26-Dec-2025 15:11:48 2025-12-26 20:11:49        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field1
26-Dec-2025 15:11:48 2025-12-26 20:11:49        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field2
26-Dec-2025 15:12:30 2025-12-26 20:11:49        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field3
26-Dec-2025 15:12:30 2025-12-26 20:11:49        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field4
26-Dec-2025 15:12:30 2025-12-26 20:11:49        INFO        plotbandpass::::casa        Skipping spw=18 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:11:51        INFO        plotbandpass::::casa        Skipping spw=20 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:11:54        INFO        plotbandpass::::casa        Skipping spw=22 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:11:56        INFO        plotbandpass::::casa        Making directory = uid___A002_Xe3a5fd_Xe38e.ms.sky.plots.overlayTime/
26-Dec-2025 15:12:30 2025-12-26 20:11:56        INFO        plotbandpass::::casa        Building uid___A002_Xe3a5fd_Xe38e.ms.sky.plots.overlayTime/uid___A002_Xe3a5fd_Xe38e.ms.sky.PM01.spw13.png
26-Dec-2025 15:12:30 2025-12-26 20:11:56        INFO        plotbandpass::::casa        Skipping spw=24 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:11:57        INFO        plotbandpass::::casa        Skipping spw=13 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:11:57        INFO        plotbandpass::::casa        Skipping spw=14 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:11:57        INFO        plotbandpass::::casa        Skipping spw=15 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:11:57        INFO        plotbandpass::::casa        Skipping spw=16 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:11:59        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field0
26-Dec-2025 15:12:30 2025-12-26 20:11:59        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field1
26-Dec-2025 15:12:30 2025-12-26 20:11:59        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field2
26-Dec-2025 15:12:30 2025-12-26 20:11:59        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field3
26-Dec-2025 15:12:30 2025-12-26 20:11:59        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field4
26-Dec-2025 15:12:30 2025-12-26 20:12:00        INFO        plotbandpass::::casa        Skipping spw=18 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:02        INFO        plotbandpass::::casa        Skipping spw=20 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:04        INFO        plotbandpass::::casa        Skipping spw=22 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:07        INFO        plotbandpass::::casa        Building uid___A002_Xe3a5fd_Xe38e.ms.sky.plots.overlayTime/uid___A002_Xe3a5fd_Xe38e.ms.sky.PM02.spw17.png
26-Dec-2025 15:12:30 2025-12-26 20:12:08        INFO        plotbandpass::::casa        Skipping spw=24 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:08        INFO        plotbandpass::::casa        Skipping spw=13 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:08        INFO        plotbandpass::::casa        Skipping spw=14 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:08        INFO        plotbandpass::::casa        Skipping spw=15 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:08        INFO        plotbandpass::::casa        Skipping spw=16 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:11        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field0
26-Dec-2025 15:12:30 2025-12-26 20:12:11        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field1
26-Dec-2025 15:12:30 2025-12-26 20:12:11        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field2
26-Dec-2025 15:12:30 2025-12-26 20:12:11        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field3
26-Dec-2025 15:12:30 2025-12-26 20:12:11        INFO        plotbandpass::::casa        plotbandpass drawOverlayTimeLegends() found 0/16 time matches for spw17 field4
26-Dec-2025 15:12:30 2025-12-26 20:12:11        INFO        plotbandpass::::casa        Skipping spw=18 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:13        INFO        plotbandpass::::casa        Skipping spw=20 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:16        INFO        plotbandpass::::casa        Skipping spw=22 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:18        INFO        plotbandpass::::casa        Building uid___A002_Xe3a5fd_Xe38e.ms.sky.plots.overlayTime/uid___A002_Xe3a5fd_Xe38e.ms.sky.PM04.spw17.png
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        plotbandpass::::casa        Skipping spw=24 because it has only 1 channel.
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        plotbandpass::::casa        Task plotbandpass complete. Start time: 2025-12-26 15:11:44.267112 End time: 2025-12-26 15:12:18.767806
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        plotbandpass::::casa        ##### End Task: plotbandpass         #####
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        plotbandpass::::casa        ##########################################
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        plotbandpass::::casa        Step 5 Create caltable to convert the science target units from Kelvin to Jansky
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        gencal::::casa        ##########################################
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        gencal::::casa        ##### Begin Task: gencal             #####
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        gencal::::casa        gencal( vis='uid___A002_Xe3a5fd_Xe38e.ms', caltable='uid___A002_Xe3a5fd_Xe38e.ms.jy', caltype='jyperk', infile='', endpoint='asdm', timeout=180, retry=3, retry_wait_time=5, ant_pos_time_limit=0, spw='17,19,21,23', antenna='', pol='', parameter=[], uniform=True )
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        calibrater::open        ****Using NEW VI2-driven calibrater tool****
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        calibrater::open        Opening MS: uid___A002_Xe3a5fd_Xe38e.ms for calibration.
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        Calibrater::        Initializing nominal selection to the whole MS.
26-Dec-2025 15:12:30 2025-12-26 20:12:19        INFO        gencal::::casa        Accessing Jy/K DB: request URL is "https://asa.alma.cl/science/jy-kelvins/asdm/?uid=uid%3A%2F%2FA002%2FXe3a5fd%2FXe38e"
26-Dec-2025 15:12:30 2025-12-26 20:12:29        INFO        gencal::::casa        Got a response successfully
26-Dec-2025 15:12:30 2025-12-26 20:12:29        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:30 2025-12-26 20:12:29        INFO                Creating G Jones table from specified parameters.
26-Dec-2025 15:12:30 2025-12-26 20:12:29        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:30 2025-12-26 20:12:29        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:30 2025-12-26 20:12:29        INFO                spwId=17 antId=0 polId=<all> parameter= 0.156716   (ip0,ip1 = [0, 0, 0],[1, 0, 0])
26-Dec-2025 15:12:30 2025-12-26 20:12:29        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:30 2025-12-26 20:12:29        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:30 2025-12-26 20:12:29        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                spwId=19 antId=0 polId=<all> parameter= 0.156737   (ip0,ip1 = [0, 0, 0],[1, 0, 0])
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                spwId=21 antId=0 polId=<all> parameter= 0.156825   (ip0,ip1 = [0, 0, 0],[1, 0, 0])
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                spwId=23 antId=0 polId=<all> parameter= 0.156841   (ip0,ip1 = [0, 0, 0],[1, 0, 0])
26-Dec-2025 15:12:30 2025-12-26 20:12:30        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:30 2025-12-26 20:12:31        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:30 2025-12-26 20:12:31        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:30 2025-12-26 20:12:31        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:30 2025-12-26 20:12:31        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:30 2025-12-26 20:12:31        INFO                spwId=17 antId=1 polId=<all> parameter= 0.156716   (ip0,ip1 = [0, 0, 1],[1, 0, 1])
26-Dec-2025 15:12:30 2025-12-26 20:12:31        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:30 2025-12-26 20:12:31        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:30 2025-12-26 20:12:31        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO                spwId=19 antId=1 polId=<all> parameter= 0.156737   (ip0,ip1 = [0, 0, 1],[1, 0, 1])
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO                spwId=21 antId=1 polId=<all> parameter= 0.156825   (ip0,ip1 = [0, 0, 1],[1, 0, 1])
26-Dec-2025 15:12:36 2025-12-26 20:12:31        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                spwId=23 antId=1 polId=<all> parameter= 0.156841   (ip0,ip1 = [0, 0, 1],[1, 0, 1])
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                spwId=17 antId=2 polId=<all> parameter= 0.156716   (ip0,ip1 = [0, 0, 2],[1, 0, 2])
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                spwId=19 antId=2 polId=<all> parameter= 0.156737   (ip0,ip1 = [0, 0, 2],[1, 0, 2])
26-Dec-2025 15:12:36 2025-12-26 20:12:32        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                spwId=21 antId=2 polId=<all> parameter= 0.156825   (ip0,ip1 = [0, 0, 2],[1, 0, 2])
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        calibrater::specifycal        Beginning specifycal-----------------------
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                Loading existing G Jones table: uid___A002_Xe3a5fd_Xe38e.ms.jy (to be updated).
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                Generating 'amp' corrections.
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                Specified parameter(s) (per spw and antenna) repeated on all polarizations.
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                spwId=23 antId=2 polId=<all> parameter= 0.156841   (ip0,ip1 = [0, 0, 2],[1, 0, 2])
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO                Writing solutions to table: uid___A002_Xe3a5fd_Xe38e.ms.jy
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        gencal::::casa        Task gencal complete. Start time: 2025-12-26 15:12:18.769243 End time: 2025-12-26 15:12:33.307782
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        gencal::::casa        ##### End Task: gencal               #####
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        gencal::::casa        ##########################################
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        gencal::::casa        Step 6 Do initial flagging
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        flagdata::::casa        ##########################################
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        flagdata::::casa        flagdata( vis='uid___A002_Xe3a5fd_Xe38e.ms', mode='manual', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='19:0~63;1984~2047,21:0~63;1984~2047,23:0~63;1984~2047', 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 )
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        flagdata::::casa        Backup original flags before applying new flags
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:12:36 2025-12-26 20:12:33        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called flagdata_1
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        flagdata::::casa        Manual mode is active
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [6, 4]  (NB: Matrix in Row/Column order)
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::selectData +        [19, 0, 63, 1
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::selectData +         19, 1984, 2047, 1
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::selectData +         21, 0, 63, 1
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::selectData +         21, 1984, 2047, 1
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::selectData +         23, 0, 63, 1
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::selectData +         23, 1984, 2047, 1]
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        flagdata::::casa        Initializing the agents
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: manual
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        Manual::setAgentParameters         autocorr is 0
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: manual
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        flagdata::::casa        Running the agentflagger tool
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::        Chunk = 1 [progress: 0%], Observation = 0, Array = 0, Scan = 2, Field = 1 (Ridge-W1_OFF_0), Spw = 19, Channels = 2048, Corrs = [ XX YY ], Total Rows = 63
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::        Chunk = 7 [progress: 12%], Observation = 0, Array = 0, Scan = 3, Field = 2 (Ridge-W1), Spw = 19, Channels = 2048, Corrs = [ XX YY ], Total Rows = 990
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (781056/12496896)
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        FlagMSHandler::        Chunk = 10 [progress: 20%], Observation = 0, Array = 0, Scan = 4, Field = 1 (Ridge-W1_OFF_0), Spw = 19, Channels = 2048, Corrs = [ XX YY ], Total Rows = 63
26-Dec-2025 15:12:36 2025-12-26 20:12:36        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (523008/8368128)
26-Dec-2025 15:12:36 2025-12-26 20:12:37        INFO        FlagMSHandler::        Chunk = 16 [progress: 31%], Observation = 0, Array = 0, Scan = 5, Field = 4 (Ridge-W2), Spw = 19, Channels = 2048, Corrs = [ XX YY ], Total Rows = 990
26-Dec-2025 15:12:36 2025-12-26 20:12:37        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (764928/12238848)
26-Dec-2025 15:12:36 2025-12-26 20:12:37        INFO        FlagMSHandler::        Chunk = 20 [progress: 40%], Observation = 0, Array = 0, Scan = 6, Field = 1 (Ridge-W1_OFF_0), Spw = 21, Channels = 2048, Corrs = [ XX YY ], Total Rows = 63
26-Dec-2025 15:12:36 2025-12-26 20:12:37        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (539136/8626176)
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        FlagMSHandler::        Chunk = 25 [progress: 51%], Observation = 0, Array = 0, Scan = 7, Field = 6 (Ridge-W3), Spw = 19, Channels = 2048, Corrs = [ XX YY ], Total Rows = 990
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (748800/11980800)
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        FlagMSHandler::        Chunk = 29 [progress: 60%], Observation = 0, Array = 0, Scan = 8, Field = 1 (Ridge-W1_OFF_0), Spw = 21, Channels = 2048, Corrs = [ XX YY ], Total Rows = 63
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (539136/8626176)
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        FlagMSHandler::        Chunk = 34 [progress: 71%], Observation = 0, Array = 0, Scan = 9, Field = 8 (Ridge-W4), Spw = 19, Channels = 2048, Corrs = [ XX YY ], Total Rows = 990
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (748800/11980800)
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        FlagMSHandler::        Chunk = 39 [progress: 80%], Observation = 0, Array = 0, Scan = 10, Field = 1 (Ridge-W1_OFF_0), Spw = 23, Channels = 2048, Corrs = [ XX YY ], Total Rows = 63
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (555264/8884224)
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        FlagMSHandler::        Chunk = 43 [progress: 91%], Observation = 0, Array = 0, Scan = 11, Field = 10 (Ridge-W5), Spw = 19, Channels = 2048, Corrs = [ XX YY ], Total Rows = 990
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (732672/11722752)
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        FlagMSHandler::        Chunk = 48 [progress: 100%], Observation = 0, Array = 0, Scan = 12, Field = 1 (Ridge-W1_OFF_0), Spw = 23, Channels = 2048, Corrs = [ XX YY ], Total Rows = 63
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        Manual::chunkSummary        => Data flagged so far 6.25% (555264/8884224)
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        FlagMSHandler::nextChunk         ====================================================================================
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 6.25%
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        AgentFlagger::run        => Writing flags to the MS
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        flagdata::::casa        Task flagdata complete. Start time: 2025-12-26 15:12:33.315714 End time: 2025-12-26 15:12:37.123585
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        flagdata::::casa        ##### End Task: flagdata             #####
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        flagdata::::casa        ##########################################
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        flagdata::::casa        ##########################################
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        flagdata::::casa        flagdata( vis='uid___A002_Xe3a5fd_Xe38e.ms', 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='after-mystep6-initial_flagging', action='apply', display='', flagbackup=True, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        flagdata::::casa        Summary mode is active
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        flagdata::::casa        Initializing the agents
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        Summary::setAgentParameters          display is:
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
26-Dec-2025 15:13:07 2025-12-26 20:12:37        INFO        flagdata::::casa        Running the agentflagger tool
26-Dec-2025 15:13:07 2025-12-26 20:12:38        INFO        FlagMSHandler::        Chunk = 1 [progress: 2%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J0529-7245), Spw = 0, Channels = 1, Corrs = [ XX YY ], Total Rows = 45360
26-Dec-2025 15:13:07 2025-12-26 20:12:41        INFO        FlagMSHandler::        Chunk = 28 [progress: 11%], Observation = 0, Array = 0, Scan = 3, Field = 1 (Ridge-W1_OFF_0), Spw = 13, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:13:07 2025-12-26 20:12:43        INFO        FlagMSHandler::        Chunk = 42 [progress: 21%], Observation = 0, Array = 0, Scan = 3, Field = 2 (Ridge-W1), Spw = 14, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:13:07 2025-12-26 20:12:47        INFO        FlagMSHandler::        Chunk = 68 [progress: 30%], Observation = 0, Array = 0, Scan = 5, Field = 3 (Ridge-W2_OFF_0), Spw = 14, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:13:07 2025-12-26 20:12:49        INFO        FlagMSHandler::        Chunk = 82 [progress: 42%], Observation = 0, Array = 0, Scan = 5, Field = 4 (Ridge-W2), Spw = 15, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:13:07 2025-12-26 20:12:52        INFO        FlagMSHandler::        Chunk = 108 [progress: 50%], Observation = 0, Array = 0, Scan = 7, Field = 5 (Ridge-W3_OFF_0), Spw = 15, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:13:07 2025-12-26 20:12:55        INFO        FlagMSHandler::        Chunk = 121 [progress: 60%], Observation = 0, Array = 0, Scan = 7, Field = 6 (Ridge-W3), Spw = 15, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:13:07 2025-12-26 20:12:58        INFO        FlagMSHandler::        Chunk = 148 [progress: 70%], Observation = 0, Array = 0, Scan = 9, Field = 7 (Ridge-W4_OFF_0), Spw = 16, Channels = 1, Corrs = [ XX YY ], Total Rows = 35568
26-Dec-2025 15:13:07 2025-12-26 20:13:01        INFO        FlagMSHandler::        Chunk = 161 [progress: 80%], Observation = 0, Array = 0, Scan = 9, Field = 8 (Ridge-W4), Spw = 16, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:13:07 2025-12-26 20:13:04        INFO        FlagMSHandler::        Chunk = 197 [progress: 91%], Observation = 0, Array = 0, Scan = 11, Field = 10 (Ridge-W5), Spw = 13, Channels = 1, Corrs = [ XX YY ], Total Rows = 56430
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        FlagMSHandler::        Chunk = 221 [progress: 100%], Observation = 0, Array = 0, Scan = 12, Field = 1 (Ridge-W1_OFF_0), Spw = 24, Channels = 1, Corrs = [ XX YY ], Total Rows = 63
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        FlagMSHandler::nextChunk         ====================================================================================
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          observation 0 flagged: 6.63255e+06 total: 1.78815e+08 (3.71%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 10 flagged: 48384 total: 1.3197e+06 (3.67%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 7 flagged: 1.25059e+06 total: 3.3809e+07 (3.7%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 2 flagged: 48396 total: 1.3197e+06 (3.67%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 1 flagged: 1256 total: 1.85154e+06 (0.0678%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 8 flagged: 48396 total: 1.3197e+06 (3.67%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 5 flagged: 1.29044e+06 total: 3.3809e+07 (3.82%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 3 flagged: 1.24072e+06 total: 3.38091e+07 (3.67%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 11 flagged: 1.28889e+06 total: 3.3809e+07 (3.81%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 9 flagged: 1.27029e+06 total: 3.3809e+07 (3.76%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 4 flagged: 48384 total: 1.3197e+06 (3.67%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 12 flagged: 48408 total: 1.31971e+06 (3.67%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          scan 6 flagged: 48396 total: 1.31971e+06 (3.67%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          antenna PM01 flagged: 2.28002e+06 total: 5.98526e+07 (3.81%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          antenna PM04 flagged: 2.17612e+06 total: 5.98526e+07 (3.64%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          antenna PM02 flagged: 2.17641e+06 total: 5.98526e+07 (3.64%)
26-Dec-2025 15:13:07 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W5_OFF_0 flagged: 524128 total: 1.30712e+07 (4.01%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W3 flagged: 765384 total: 2.07378e+07 (3.69%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W4_OFF_0 flagged: 485004 total: 1.30712e+07 (3.71%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W1_OFF_0 flagged: 769748 total: 2.09894e+07 (3.67%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field J0529-7245 flagged: 1256 total: 1.85154e+06 (0.0678%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W1 flagged: 761340 total: 2.07379e+07 (3.67%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W4 flagged: 785288 total: 2.07378e+07 (3.79%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W2_OFF_0 flagged: 525012 total: 1.30712e+07 (4.02%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W2 flagged: 765424 total: 2.07378e+07 (3.69%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W5 flagged: 764764 total: 2.07378e+07 (3.69%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          field Ridge-W3_OFF_0 flagged: 485204 total: 1.30712e+07 (3.71%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 23 flagged: 2.18189e+06 total: 3.4603e+07 (6.31%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 20 flagged: 10 total: 16896 (0.0592%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 18 flagged: 10 total: 16896 (0.0592%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 24 flagged: 10 total: 16896 (0.0592%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 17 flagged: 40960 total: 6.9206e+07 (0.0592%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 16 flagged: 10750 total: 963072 (1.12%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 3 flagged: 300 total: 90720 (0.331%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 13 flagged: 10750 total: 963072 (1.12%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 21 flagged: 2.18189e+06 total: 3.4603e+07 (6.31%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 4 flagged: 1688 total: 30864 (5.47%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 9 flagged: 0 total: 368640 (0%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 11 flagged: 0 total: 368640 (0%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 0 flagged: 300 total: 90720 (0.331%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 22 flagged: 10 total: 16896 (0.0592%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 5 flagged: 0 total: 368640 (0%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 8 flagged: 0 total: 2880 (0%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 6 flagged: 0 total: 2880 (0%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 1 flagged: 300 total: 90720 (0.331%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 12 flagged: 0 total: 2880 (0%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 7 flagged: 0 total: 368640 (0%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 10 flagged: 0 total: 2880 (0%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 2 flagged: 300 total: 90720 (0.331%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 14 flagged: 10750 total: 963072 (1.12%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 19 flagged: 2.18189e+06 total: 3.4603e+07 (6.31%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          spw 15 flagged: 10750 total: 963072 (1.12%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          correlation YY flagged: 3.31543e+06 total: 8.93919e+07 (3.71%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          correlation XX flagged: 3.31712e+06 total: 8.94228e+07 (3.71%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          array 0 flagged: 6.63255e+06 total: 1.78815e+08 (3.71%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        Summary::getResult          Total Flagged: 6.63255e+06 Total Counts: 1.78815e+08 (3.71%)
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        flagdata::::casa        Task flagdata complete. Start time: 2025-12-26 15:12:37.130473 End time: 2025-12-26 15:13:07.052089
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        flagdata::::casa        ##### End Task: flagdata             #####
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        flagdata::::casa        ##########################################
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        flagdata::::casa        after-mystep6-initial_flagging flags_initial
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        flagdata::::casa        Step 7 Calibration of the data into Janskys
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        flagdata::::casa        Found no match for following spw ids: [0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 18, 20, 22, 24, 25, 26, 27]
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        applycal::::casa        ##### Begin Task: applycal           #####
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        applycal::::casa        applycal( vis='uid___A002_Xe3a5fd_Xe38e.ms', field='2', spw='17,19,21,23', intent='', selectdata=True, timerange='', uvrange='', antenna='', scan='', observation='', msselect='', docallib=False, callib='', gaintable=['uid___A002_Xe3a5fd_Xe38e.ms.tsys', 'uid___A002_Xe3a5fd_Xe38e.ms.sky', 'uid___A002_Xe3a5fd_Xe38e.ms.jy'], gainfield=['nearest', '1'], interp=[], spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27], calwt=[True], parang=False, applymode='calflagstrict', flagbackup=True )
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        applycal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        applycal::calibrater::open        Opening MS: uid___A002_Xe3a5fd_Xe38e.ms for calibration.
26-Dec-2025 15:13:15 2025-12-26 20:13:07        INFO        applycal::VisSetUtil::addScrCols        Adding CORRECTED_DATA column(s).
26-Dec-2025 15:13:15 2025-12-26 20:13:13        INFO        applycal::Calibrater::        Initializing nominal selection to the whole MS.
26-Dec-2025 15:13:15 2025-12-26 20:13:13        INFO        applycal::AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:13:15 2025-12-26 20:13:13        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called applycal_1
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        calibrater::reset        Reseting solve/apply state
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::selectvis+         Selecting on spw: '17,19,21,23'
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::selectvis+         Selecting on field: '2'
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::selectvis        By selection 2194404 rows are reduced to 3960
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::selectvis        Frequency selection:
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::selectvis        .  Spw 17:0~4095 (4096 channels, step by 1)
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::selectvis+        .  Spw 19:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::selectvis+        .  Spw 21:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::selectvis+        .  Spw 23:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO                Calibration field mapping for uid___A002_Xe3a5fd_Xe38e.ms.tsys (via gainfield='nearest'): [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        +         Separations (deg): []
26-Dec-2025 15:13:15 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.tsys: 0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 18 20 22 24 25 26 27
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::setapply(type, applypar)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:15 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.sky: 0 1 2 3 4 5 6 7 8 9 10 11 12 25 26 27
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::setapply(type, applypar)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:15 2025-12-26 20:13:15        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:27 2025-12-26 20:13:16        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:16        INFO        calibrater::correct        Beginning correct---------------------------
26-Dec-2025 15:13:27 2025-12-26 20:13:16        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
26-Dec-2025 15:13:27 2025-12-26 20:13:16        INFO        Calibrater::correct2 (VI2/VB2)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:16        INFO        Calibrater::correct2 (VI2/VB2)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:16        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:20        INFO        calibrater::correct        Finished correcting.
26-Dec-2025 15:13:27 2025-12-26 20:13:20        INFO        applycal::::casa        Calibration apply flagging statistics (among calibrateable spws):
26-Dec-2025 15:13:27 2025-12-26 20:13:20        INFO        applycal::::casa          Total visibilities selected for correction (ncorr x nchan x nrow summed over spws) = 20275200
26-Dec-2025 15:13:27 2025-12-26 20:13:20        INFO        applycal::::casa          Flags:
26-Dec-2025 15:13:27 2025-12-26 20:13:20        INFO        applycal::::casa           SDSKY_PS: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.sky)
26-Dec-2025 15:13:27 2025-12-26 20:13:20        INFO        applycal::::casa           B TSYS: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.tsys)
26-Dec-2025 15:13:27 2025-12-26 20:13:20        INFO        applycal::::casa           G Jones: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.jy)
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::::casa        Task applycal complete. Start time: 2025-12-26 15:13:07.225567 End time: 2025-12-26 15:13:21.353969
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::::casa        ##### End Task: applycal             #####
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::::casa        ##### Begin Task: applycal           #####
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::::casa        applycal( vis='uid___A002_Xe3a5fd_Xe38e.ms', field='4', spw='17,19,21,23', intent='', selectdata=True, timerange='', uvrange='', antenna='', scan='', observation='', msselect='', docallib=False, callib='', gaintable=['uid___A002_Xe3a5fd_Xe38e.ms.tsys', 'uid___A002_Xe3a5fd_Xe38e.ms.sky', 'uid___A002_Xe3a5fd_Xe38e.ms.jy'], gainfield=['nearest', '3'], interp=[], spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27], calwt=[True], parang=False, applymode='calflagstrict', flagbackup=True )
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::calibrater::open        Opening MS: uid___A002_Xe3a5fd_Xe38e.ms for calibration.
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::Calibrater::        Initializing nominal selection to the whole MS.
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        applycal::AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:13:27 2025-12-26 20:13:21        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called applycal_2
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        calibrater::reset        Reseting solve/apply state
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::selectvis+         Selecting on spw: '17,19,21,23'
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::selectvis+         Selecting on field: '4'
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::selectvis        By selection 2194404 rows are reduced to 3960
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::selectvis        Frequency selection:
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::selectvis        .  Spw 17:0~4095 (4096 channels, step by 1)
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::selectvis+        .  Spw 19:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::selectvis+        .  Spw 21:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::selectvis+        .  Spw 23:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO                Calibration field mapping for uid___A002_Xe3a5fd_Xe38e.ms.tsys (via gainfield='nearest'): [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        +         Separations (deg): []
26-Dec-2025 15:13:27 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.tsys: 0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 18 20 22 24 25 26 27
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::setapply(type, applypar)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:27 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.sky: 0 1 2 3 4 5 6 7 8 9 10 11 12 25 26 27
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::setapply(type, applypar)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        calibrater::correct        Beginning correct---------------------------
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::correct2 (VI2/VB2)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::correct2 (VI2/VB2)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:24        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        calibrater::correct        Finished correcting.
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa        Calibration apply flagging statistics (among calibrateable spws):
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa          Total visibilities selected for correction (ncorr x nchan x nrow summed over spws) = 20275200
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa          Flags:
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa           SDSKY_PS: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.sky)
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa           B TSYS: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.tsys)
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa           G Jones: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.jy)
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa        Task applycal complete. Start time: 2025-12-26 15:13:21.355616 End time: 2025-12-26 15:13:27.466991
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa        ##### End Task: applycal             #####
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:27 2025-12-26 20:13:27        INFO        applycal::::casa        ##### Begin Task: applycal           #####
26-Dec-2025 15:13:36 2025-12-26 20:13:27        INFO        applycal::::casa        applycal( vis='uid___A002_Xe3a5fd_Xe38e.ms', field='6', spw='17,19,21,23', intent='', selectdata=True, timerange='', uvrange='', antenna='', scan='', observation='', msselect='', docallib=False, callib='', gaintable=['uid___A002_Xe3a5fd_Xe38e.ms.tsys', 'uid___A002_Xe3a5fd_Xe38e.ms.sky', 'uid___A002_Xe3a5fd_Xe38e.ms.jy'], gainfield=['nearest', '5'], interp=[], spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27], calwt=[True], parang=False, applymode='calflagstrict', flagbackup=True )
26-Dec-2025 15:13:36 2025-12-26 20:13:27        INFO        applycal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
26-Dec-2025 15:13:36 2025-12-26 20:13:27        INFO        applycal::calibrater::open        Opening MS: uid___A002_Xe3a5fd_Xe38e.ms for calibration.
26-Dec-2025 15:13:36 2025-12-26 20:13:27        INFO        applycal::Calibrater::        Initializing nominal selection to the whole MS.
26-Dec-2025 15:13:36 2025-12-26 20:13:27        INFO        applycal::AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:13:36 2025-12-26 20:13:27        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called applycal_3
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        calibrater::reset        Reseting solve/apply state
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::selectvis+         Selecting on spw: '17,19,21,23'
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::selectvis+         Selecting on field: '6'
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::selectvis        By selection 2194404 rows are reduced to 3960
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::selectvis        Frequency selection:
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::selectvis        .  Spw 17:0~4095 (4096 channels, step by 1)
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::selectvis+        .  Spw 19:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::selectvis+        .  Spw 21:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::selectvis+        .  Spw 23:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO                Calibration field mapping for uid___A002_Xe3a5fd_Xe38e.ms.tsys (via gainfield='nearest'): [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        +         Separations (deg): []
26-Dec-2025 15:13:36 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.tsys: 0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 18 20 22 24 25 26 27
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::setapply(type, applypar)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:36 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.sky: 0 1 2 3 4 5 6 7 8 9 10 11 12 25 26 27
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::setapply(type, applypar)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        calibrater::correct        Beginning correct---------------------------
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::correct2 (VI2/VB2)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::correct2 (VI2/VB2)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:36 2025-12-26 20:13:30        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        calibrater::correct        Finished correcting.
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa        Calibration apply flagging statistics (among calibrateable spws):
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa          Total visibilities selected for correction (ncorr x nchan x nrow summed over spws) = 20275200
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa          Flags:
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa           SDSKY_PS: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.sky)
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa           B TSYS: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.tsys)
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa           G Jones: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.jy)
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa        Task applycal complete. Start time: 2025-12-26 15:13:27.468233 End time: 2025-12-26 15:13:33.465198
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa        ##### End Task: applycal             #####
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa        ##### Begin Task: applycal           #####
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::::casa        applycal( vis='uid___A002_Xe3a5fd_Xe38e.ms', field='8', spw='17,19,21,23', intent='', selectdata=True, timerange='', uvrange='', antenna='', scan='', observation='', msselect='', docallib=False, callib='', gaintable=['uid___A002_Xe3a5fd_Xe38e.ms.tsys', 'uid___A002_Xe3a5fd_Xe38e.ms.sky', 'uid___A002_Xe3a5fd_Xe38e.ms.jy'], gainfield=['nearest', '7'], interp=[], spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27], calwt=[True], parang=False, applymode='calflagstrict', flagbackup=True )
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::calibrater::open        Opening MS: uid___A002_Xe3a5fd_Xe38e.ms for calibration.
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::Calibrater::        Initializing nominal selection to the whole MS.
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        applycal::AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:13:36 2025-12-26 20:13:33        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called applycal_4
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        calibrater::reset        Reseting solve/apply state
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::selectvis+         Selecting on spw: '17,19,21,23'
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::selectvis+         Selecting on field: '8'
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::selectvis        By selection 2194404 rows are reduced to 3960
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::selectvis        Frequency selection:
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::selectvis        .  Spw 17:0~4095 (4096 channels, step by 1)
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::selectvis+        .  Spw 19:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::selectvis+        .  Spw 21:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::selectvis+        .  Spw 23:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO                Calibration field mapping for uid___A002_Xe3a5fd_Xe38e.ms.tsys (via gainfield='nearest'): [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        +         Separations (deg): []
26-Dec-2025 15:13:36 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.tsys: 0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 18 20 22 24 25 26 27
26-Dec-2025 15:13:36 2025-12-26 20:13:36        INFO        Calibrater::setapply(type, applypar)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:45 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.sky: 0 1 2 3 4 5 6 7 8 9 10 11 12 25 26 27
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        Calibrater::setapply(type, applypar)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        calibrater::correct        Beginning correct---------------------------
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        Calibrater::correct2 (VI2/VB2)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        Calibrater::correct2 (VI2/VB2)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:36        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:39        INFO        calibrater::correct        Finished correcting.
26-Dec-2025 15:13:45 2025-12-26 20:13:39        INFO        applycal::::casa        Calibration apply flagging statistics (among calibrateable spws):
26-Dec-2025 15:13:45 2025-12-26 20:13:39        INFO        applycal::::casa          Total visibilities selected for correction (ncorr x nchan x nrow summed over spws) = 20275200
26-Dec-2025 15:13:45 2025-12-26 20:13:39        INFO        applycal::::casa          Flags:
26-Dec-2025 15:13:45 2025-12-26 20:13:39        INFO        applycal::::casa           SDSKY_PS: In: 780032 / 20275200   (3.8472222222222223%) --> Out: 780032 / 20275200   (3.8472222222222223%) (uid___A002_Xe3a5fd_Xe38e.ms.sky)
26-Dec-2025 15:13:45 2025-12-26 20:13:39        INFO        applycal::::casa           B TSYS: In: 780032 / 20275200   (3.8472222222222223%) --> Out: 780032 / 20275200   (3.8472222222222223%) (uid___A002_Xe3a5fd_Xe38e.ms.tsys)
26-Dec-2025 15:13:45 2025-12-26 20:13:39        INFO        applycal::::casa           G Jones: In: 780032 / 20275200   (3.8472222222222223%) --> Out: 780032 / 20275200   (3.8472222222222223%) (uid___A002_Xe3a5fd_Xe38e.ms.jy)
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::::casa        Task applycal complete. Start time: 2025-12-26 15:13:33.466541 End time: 2025-12-26 15:13:39.524651
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::::casa        ##### End Task: applycal             #####
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::::casa        ##### Begin Task: applycal           #####
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::::casa        applycal( vis='uid___A002_Xe3a5fd_Xe38e.ms', field='10', spw='17,19,21,23', intent='', selectdata=True, timerange='', uvrange='', antenna='', scan='', observation='', msselect='', docallib=False, callib='', gaintable=['uid___A002_Xe3a5fd_Xe38e.ms.tsys', 'uid___A002_Xe3a5fd_Xe38e.ms.sky', 'uid___A002_Xe3a5fd_Xe38e.ms.jy'], gainfield=['nearest', '9'], interp=[], spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27], calwt=[True], parang=False, applymode='calflagstrict', flagbackup=True )
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::calibrater::open        ****Using NEW VI2-driven calibrater tool****
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::calibrater::open        Opening MS: uid___A002_Xe3a5fd_Xe38e.ms for calibration.
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::Calibrater::        Initializing nominal selection to the whole MS.
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        applycal::AgentFlagger::open        Table type is Measurement Set
26-Dec-2025 15:13:45 2025-12-26 20:13:40        INFO        FlagVersion::saveFlagVersion        Creating new backup flag file called applycal_5
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        calibrater::setdata        Beginning selectvis--(MSSelection version)-------
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        calibrater::reset        Reseting solve/apply state
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::selectvis        Performing selection on MeasurementSet
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::selectvis+         Selecting on spw: '17,19,21,23'
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::selectvis+         Selecting on field: '10'
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::selectvis        By selection 2194404 rows are reduced to 3960
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::selectvis        Frequency selection:
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::selectvis        .  Spw 17:0~4095 (4096 channels, step by 1)
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::selectvis+        .  Spw 19:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::selectvis+        .  Spw 21:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::selectvis+        .  Spw 23:0~2047 (2048 channels, step by 1)
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        calibrater::setdata        chanmode=none nchan=1 start=0 step=1 mStart='0km/s' mStep='0km/s' msSelect=''
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO                Calibration field mapping for uid___A002_Xe3a5fd_Xe38e.ms.tsys (via gainfield='nearest'): [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        +         Separations (deg): []
26-Dec-2025 15:13:45 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.tsys: 0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 18 20 22 24 25 26 27
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::setapply(type, applypar)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:45 The following MS spws have no corresponding cal spws in uid___A002_Xe3a5fd_Xe38e.ms.sky: 0 1 2 3 4 5 6 7 8 9 10 11 12 25 26 27
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::setapply(type, applypar)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        calibrater::setapply        Beginning setapply--(MSSelection version)-------
26-Dec-2025 15:13:45 2025-12-26 20:13:42        INFO        Calibrater::setapply(type, applypar)        Arranging to APPLY:
26-Dec-2025 15:13:45 2025-12-26 20:13:43        INFO        Calibrater::setapply(type, applypar)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:43        INFO        calibrater::correct        Beginning correct---------------------------
26-Dec-2025 15:13:45 2025-12-26 20:13:43        INFO        Calibrater::correct2 (VI2/VB2)        The following calibration terms are arranged for apply:
26-Dec-2025 15:13:45 2025-12-26 20:13:43        INFO        Calibrater::correct2 (VI2/VB2)        .   SDSKY_PS: table=uid___A002_Xe3a5fd_Xe38e.ms.sky select= interp=linear,linearflag spwmap=[-1] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:43        INFO        Calibrater::correct2 (VI2/VB2)        .   B TSYS: table=uid___A002_Xe3a5fd_Xe38e.ms.tsys select= interp=linear,linear spwmap=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:43        INFO        Calibrater::correct2 (VI2/VB2)        .   G Jones: table=uid___A002_Xe3a5fd_Xe38e.ms.jy select= interp=linear spwmap=[-1] calWt=true
26-Dec-2025 15:13:45 2025-12-26 20:13:45        INFO        calibrater::correct        Finished correcting.
26-Dec-2025 15:13:45 2025-12-26 20:13:45        INFO        applycal::::casa        Calibration apply flagging statistics (among calibrateable spws):
26-Dec-2025 15:13:45 2025-12-26 20:13:45        INFO        applycal::::casa          Total visibilities selected for correction (ncorr x nchan x nrow summed over spws) = 20275200
26-Dec-2025 15:13:45 2025-12-26 20:13:45        INFO        applycal::::casa          Flags:
26-Dec-2025 15:13:45 2025-12-26 20:13:45        INFO        applycal::::casa           SDSKY_PS: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.sky)
26-Dec-2025 15:13:45 2025-12-26 20:13:45        INFO        applycal::::casa           B TSYS: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.tsys)
26-Dec-2025 15:13:47 2025-12-26 20:13:45        INFO        applycal::::casa           G Jones: In: 760320 / 20275200   (3.75%) --> Out: 760320 / 20275200   (3.75%) (uid___A002_Xe3a5fd_Xe38e.ms.jy)
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        applycal::::casa        Task applycal complete. Start time: 2025-12-26 15:13:39.525950 End time: 2025-12-26 15:13:45.533535
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        applycal::::casa        ##### End Task: applycal             #####
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        applycal::::casa        ##########################################
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        applycal::::casa        Step 8 Correction of residual atmospheric features (sdatmcor)
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa        ##########################################
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa        ##### Begin Task: sdatmcor           #####
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa        sdatmcor( infile='uid___A002_Xe3a5fd_Xe38e.ms', datacolumn='corrected', outfile='uid___A002_Xe3a5fd_Xe38e.ms.atmcor.atmtype1', overwrite=True, field='2,4,6,8,10', spw='', scan='', antenna='', correlation='', timerange='', intent='OBSERVE_TARGET#ON_SOURCE', observation='', feed='', msselect='', outputspw='17,19,21,23', gainfactor='uid___A002_Xe3a5fd_Xe38e.ms.jy', dtem_dh='', h0='', atmtype=1, atmdetail=False, altitude='', temperature='', pressure='', humidity=-1.0, pwv='', dp='', dpm=-1.0, layerboundaries='', layertemperature='' )
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa        INPUT/OUTPUT
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa          Input MS file   = uid___A002_Xe3a5fd_Xe38e.ms
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa          Output MS file  = uid___A002_Xe3a5fd_Xe38e.ms.atmcor.atmtype1
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        SingleDishMS::setSelection         [Selection Summary]
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        SingleDishMS::setSelection         - Field: 2,4,6,8,10
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        SingleDishMS::setSelection         - SPW: 17,19,21,23
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        SingleDishMS::setSelection         - Intent: OBSERVE_TARGET#ON_SOURCE
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        SingleDishMS::setSelection         - Reindex: OFF
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on uid___A002_Xe3a5fd_Xe38e.ms...
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on uid___A002_Xe3a5fd_Xe38e.ms...
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        msmetadata_cmpt.cc::open        Performing internal consistency checks on uid___A002_Xe3a5fd_Xe38e.ms...
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa        Select PM01 (ID 0) as a default antenna
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa        Default Altitude
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa         - Antenna ID: 0.
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa         - Ref = ITRF.
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa         - Position: (2225104.7031870894, -5440102.473434734, -2481568.6898296387).
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        sdatmcor::::casa           Altitude (geodetic elevation):  5079.966073
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        SingleDishMS::format_selection         Formatting antenna selection to select only auto-correlation
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::parseMsSpecParams        Input file name is uid___A002_Xe3a5fd_Xe38e.ms
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::parseMsSpecParams        Data column is CORRECTED
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::parseMsSpecParams        Output file name is uid___A002_Xe3a5fd_Xe38e.ms.atmcor.atmtype1
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::parseMsSpecParams        Re-index is disabled
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::parseDataSelParams        field selection is 2,4,6,8,10
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::parseDataSelParams        spw selection is 17,19,21,23
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::parseDataSelParams        antenna selection is *&&&
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::parseDataSelParams        scan intent selection is OBSERVE_TARGET#ON_SOURCE
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::colCheckInfo        Adding DATA column to output MS from input CORRECTED_DATA column
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::initDataSelectionParams        Selected SPWs Ids are Axis Lengths: [4, 4]  (NB: Matrix in Row/Column order)
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::initDataSelectionParams+        [17, 0, 4095, 1
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::initDataSelectionParams+         19, 0, 2047, 1
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::initDataSelectionParams+         21, 0, 2047, 1
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::initDataSelectionParams+         23, 0, 2047, 1]
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::open        Select data
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformManager::createOutputMSStructure        Create output MS structure
26-Dec-2025 15:13:47 2025-12-26 20:13:46        INFO        MSTransformDataHandler::makeSelection        19800 out of 2194404 rows are going to be considered due to the selection criteria.
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDMSManager::setSortColumns         Setting user sort columns with 4 elements
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDMSManager::setSortColumns         Defined user sort columns with 4 elements
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDMSManager::setSortColumns         Time bin is 0 sec
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::SDAtmosphereCorrectionTVI         Setting numThreads_ to 8
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SingleDishMS::atmcor         There are 3 antennas in MAIN table. Set row-blocking size 1080
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereCorrection         processspw (input) = [17, 19, 21, 23]
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereCorrection         gainfactor = [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 40.717, 1, 40.706, 1, 40.66, 1, 40.652, 1, 1, 1, 1]
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereCorrection         reference antenna ID = 0
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereCorrection         input MS = "uid___A002_Xe3a5fd_Xe38e.ms"
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereCorrection         Spw 17 in BB_1 (total Nchan within BB is 8192,  sp avg likely not applied). dosmooth=True
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereCorrection         Spw 19 in BB_2 (total Nchan within BB is 4096,  sp avg likely  applied). dosmooth=False
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereCorrection         Spw 21 in BB_3 (total Nchan within BB is 4096,  sp avg likely  applied). dosmooth=False
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereCorrection         Spw 23 in BB_4 (total Nchan within BB is 4096,  sp avg likely  applied). dosmooth=False
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::readAsdmAsIsTables         CALWVR table "/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/test_stk_sd_MultiTargetImaging/uid___A002_Xe3a5fd_Xe38e.ms/ASDM_CALWVR"
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::readAsdmAsIsTables         CALATMOSPHERE table "/export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE/work/test_stk_sd_MultiTargetImaging/uid___A002_Xe3a5fd_Xe38e.ms/ASDM_CALATMOSPHERE"
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::readAsdmAsIsTables         PWV = 4.72383mm, T = 270.559K, P = 556.747hPa, H = 90.1719% at 2019-11-19T06:35:49
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::readAsdmAsIsTables         PWV = 4.62682mm, T = 270.559K, P = 556.701hPa, H = 89.1825% at 2019-11-19T06:46:38
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::readAsdmAsIsTables         PWV = 4.62397mm, T = 270.453K, P = 556.641hPa, H = 87.7436% at 2019-11-19T06:56:01
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::readAsdmAsIsTables         PWV = 4.563mm, T = 270.439K, P = 556.631hPa, H = 88.2761% at 2019-11-19T07:05:25
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::readAsdmAsIsTables         PWV = 4.5567mm, T = 270.117K, P = 556.578hPa, H = 90.7975% at 2019-11-19T07:14:49
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::readAsdmAsIsTables         PWV = 4.51593mm, T = 269.946K, P = 556.522hPa, H = 93.0367% at 2019-11-19T07:24:14
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeChunk         Process chunk 0-0 SPW 17
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereModel         atmTime_ size 6
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereModel          atmTime_[0] = 5080862149.105385 (current Time 5080862282.232)
26-Dec-2025 15:13:47 2025-12-26 20:13:47        INFO        SDAtmosphereCorrectionTVI::initializeAtmosphereModel          atmTime_[1] = 5080862798.094441 (current Time 5080862282.232)
26-Dec-2025 15:30:07 Build CASASPECS-MASTERTESTSTKPY312-TASSMTIML2CE-192 had to be cancelled: it was marked as in progress in DB but no agents were assigned to it.