Build: #107 was successful

Job: Regressions Rocky 8.5 Docker was successful

Build log

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

11-Sep-2024 02:04:54 2024-09-11 02:04:54        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:04:54 2024-09-11 02:04:54        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:04:54 2024-09-11 02:04:54        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
11-Sep-2024 02:04:54 2024-09-11 02:04:54        INFO        Summary::setAgentParameters          display is:
11-Sep-2024 02:04:54 2024-09-11 02:04:54        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
11-Sep-2024 02:04:54 2024-09-11 02:04:54        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:04:54 2024-09-11 02:04:54        INFO        FlagMSHandler::        Chunk = 1 [progress: 53%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 30450
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        FlagMSHandler::        Chunk = 2 [progress: 100%], Observation = 0, Array = 0, Scan = 11, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 26535
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          observation 0 flagged: 9.61527e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          scan 11 flagged: 4.47742e+06 total: 1.75662e+07 (25.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          scan 1 flagged: 5.13785e+06 total: 2.01579e+07 (25.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 29 flagged: 669363 total: 2.51494e+06 (26.6%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 27 flagged: 584612 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 26 flagged: 584671 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 28 flagged: 670632 total: 2.51494e+06 (26.7%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 23 flagged: 336747 total: 2.51494e+06 (13.4%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 20 flagged: 350504 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 18 flagged: 584931 total: 2.51494e+06 (23.3%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 24 flagged: 332027 total: 2.51494e+06 (13.2%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 17 flagged: 609421 total: 2.51494e+06 (24.2%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 6 flagged: 347011 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 21 flagged: 344994 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 4 flagged: 370054 total: 2.51494e+06 (14.7%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 9 flagged: 356517 total: 2.51494e+06 (14.2%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 11 flagged: 341935 total: 2.51494e+06 (13.6%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 3 flagged: 348134 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 13 flagged: 350377 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 22 flagged: 338479 total: 2.51494e+06 (13.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 5 flagged: 344858 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 30 flagged: 674675 total: 2.51494e+06 (26.8%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 8 flagged: 364740 total: 2.51494e+06 (14.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 25 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 12 flagged: 348671 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 1 flagged: 353532 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 7 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 10 flagged: 354763 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 2 flagged: 353103 total: 2.51494e+06 (14%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 14 flagged: 352533 total: 2.51494e+06 (14%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 19 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 15 flagged: 511191 total: 2.51494e+06 (20.3%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          antenna 16 flagged: 507251 total: 2.51494e+06 (20.2%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          field 3C147 flagged: 9.61527e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          spw 0 flagged: 9.61527e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          correlation LL flagged: 4.81284e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          correlation RR flagged: 4.80243e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          array 0 flagged: 9.61527e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        Summary::getResult          Total Flagged: 9.61527e+06 Total Counts: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:04:54.449420 End time: 2024-09-11 02:04:54.879744
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        flagdata::::casa         * Flagging 3ctst_copy.ms
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:04:54 2024-09-11 02:04:55        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='rflag', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', field='0', antenna='', uvrange='', timerange='', correlation='', scan='', intent='', array='', observation='', feed='', clipminmax=[], datacolumn='data', clipoutside=True, channelavg=False, chanbin=1, timeavg=True, timebin='3min', clipzeros=False, quackinterval=1.0, quackmode='beg', quackincrement=False, tolerance=0.0, addantenna='', lowerlimit=0.0, upperlimit=90.0, ntime='scan', combinescans=True, timecutoff=4.0, freqcutoff=3.0, timefit='line', freqfit='poly', maxnpieces=7, flagdimension='freqtime', usewindowstats='none', halfwin=1, extendflags=False, winsize=3, timedev='', freqdev='', timedevscale=7.0, freqdevscale=7.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='none', flagbackup=False, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        flagdata::::casa        Rflag mode is active
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        flagdata::::casa        Parsing the display parameters
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        AgentFlagger::initAgents        There are initially 2 agents in the list. Agents: rflag, display
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters          data column is DATA
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters          Visibility expression is ABS RR,LL
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters         Time average bin is 180
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters         display is: none
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters         writeflags is: 1
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters         (writeflags OR display)=(1,none), will apply flags on modified flag cube
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters         winsize is 3
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters         spectralmax is 1e+06
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters         spectralmin is 0
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters         timedevscale is 7
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters         freqdevscale is 7
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters        No timedev value given. Will Use automatically computed values if applying flags.
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Rflag::setAgentParameters        No freqdev value given. Will Use automatically computed values if applying flags.
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Display::setAgentParameters          data column is DATA
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Display::setAgentParameters          Visibility expression is ABS RR,LL
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Display::setAgentParameters          pause is 1
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Display::setAgentParameters          datadisplay is 0
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Display::setAgentParameters          reportdisplay is 0
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        Display::setAgentParameters          format is screen
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        AgentFlagger::initAgents        There are 2 valid agents in the list. Agents: rflag, display
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:04:58 2024-09-11 02:04:55        INFO        FlagDataHandler::preLoadColumn         Setting row blocking to number of rows in selected table: 56985
11-Sep-2024 02:04:58 2024-09-11 02:04:57        INFO        FlagMSHandler::        Chunk = 1 [progress: 100%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 56985
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , 3C147 Spw : 0 Scan : 1 : 11
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        Rflag::chunkSummary        => Data flagged so far 0.00162992% (73/4478760)
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        Rflag::tableSummary        => Percentage of data flagged in table selection: 0.00162992%
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        AgentFlagger::run        => Writing flags to the MS
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        Display::displayReports         Report Displays are turned OFF
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:04:54.887797 End time: 2024-09-11 02:04:58.453685
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='summary', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', 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 )
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        flagdata::::casa        Summary mode is active
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        Summary::setAgentParameters          display is:
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
11-Sep-2024 02:04:58 2024-09-11 02:04:58        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:04:58 2024-09-11 02:04:59        INFO        FlagMSHandler::        Chunk = 1 [progress: 53%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 30450
11-Sep-2024 02:04:58 2024-09-11 02:04:59        INFO        FlagMSHandler::        Chunk = 2 [progress: 100%], Observation = 0, Array = 0, Scan = 11, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 26535
11-Sep-2024 02:04:58 2024-09-11 02:04:59        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:04:58 2024-09-11 02:04:59        INFO        Summary::getResult          observation 0 flagged: 9.61534e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:58 2024-09-11 02:04:59        INFO        Summary::getResult          scan 11 flagged: 4.47749e+06 total: 1.75662e+07 (25.5%)
11-Sep-2024 02:04:58 2024-09-11 02:04:59        INFO        Summary::getResult          scan 1 flagged: 5.13785e+06 total: 2.01579e+07 (25.5%)
11-Sep-2024 02:04:58 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 29 flagged: 669363 total: 2.51494e+06 (26.6%)
11-Sep-2024 02:04:58 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 27 flagged: 584615 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:04:58 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 26 flagged: 584676 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 28 flagged: 670637 total: 2.51494e+06 (26.7%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 23 flagged: 336754 total: 2.51494e+06 (13.4%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 20 flagged: 350508 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 18 flagged: 584934 total: 2.51494e+06 (23.3%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 24 flagged: 332031 total: 2.51494e+06 (13.2%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 17 flagged: 609440 total: 2.51494e+06 (24.2%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 6 flagged: 347016 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 21 flagged: 344996 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 4 flagged: 370064 total: 2.51494e+06 (14.7%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 9 flagged: 356521 total: 2.51494e+06 (14.2%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 11 flagged: 341940 total: 2.51494e+06 (13.6%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 3 flagged: 348138 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 13 flagged: 350381 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 22 flagged: 338483 total: 2.51494e+06 (13.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 5 flagged: 344861 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 30 flagged: 674681 total: 2.51494e+06 (26.8%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 8 flagged: 364743 total: 2.51494e+06 (14.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 25 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 12 flagged: 348677 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 1 flagged: 353539 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 7 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 10 flagged: 354770 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 2 flagged: 353111 total: 2.51494e+06 (14%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 14 flagged: 352540 total: 2.51494e+06 (14%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 19 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 15 flagged: 511196 total: 2.51494e+06 (20.3%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 16 flagged: 507257 total: 2.51494e+06 (20.2%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          field 3C147 flagged: 9.61534e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          spw 0 flagged: 9.61534e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          correlation LL flagged: 4.81288e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          correlation RR flagged: 4.80246e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          array 0 flagged: 9.61534e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          Total Flagged: 9.61534e+06 Total Counts: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:04:58.461197 End time: 2024-09-11 02:04:58.897730
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa         * Step 4. Total flagged before: 9615270.0 (25.488%), after: 9615343.0 (25.489%). Used timebin: 3min, scale: 7
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='summary', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', 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 )
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        Summary mode is active
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::setAgentParameters          display is:
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        FlagMSHandler::        Chunk = 1 [progress: 53%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 30450
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        FlagMSHandler::        Chunk = 2 [progress: 100%], Observation = 0, Array = 0, Scan = 11, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 26535
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          observation 0 flagged: 9.61534e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          scan 11 flagged: 4.47749e+06 total: 1.75662e+07 (25.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          scan 1 flagged: 5.13785e+06 total: 2.01579e+07 (25.5%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 29 flagged: 669363 total: 2.51494e+06 (26.6%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 27 flagged: 584615 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 26 flagged: 584676 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 28 flagged: 670637 total: 2.51494e+06 (26.7%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 23 flagged: 336754 total: 2.51494e+06 (13.4%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 20 flagged: 350508 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 18 flagged: 584934 total: 2.51494e+06 (23.3%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 24 flagged: 332031 total: 2.51494e+06 (13.2%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 17 flagged: 609440 total: 2.51494e+06 (24.2%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 6 flagged: 347016 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 21 flagged: 344996 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 4 flagged: 370064 total: 2.51494e+06 (14.7%)
11-Sep-2024 02:04:59 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 9 flagged: 356521 total: 2.51494e+06 (14.2%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 11 flagged: 341940 total: 2.51494e+06 (13.6%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 3 flagged: 348138 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 13 flagged: 350381 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 22 flagged: 338483 total: 2.51494e+06 (13.5%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 5 flagged: 344861 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 30 flagged: 674681 total: 2.51494e+06 (26.8%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 8 flagged: 364743 total: 2.51494e+06 (14.5%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 25 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 12 flagged: 348677 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 1 flagged: 353539 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 7 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 10 flagged: 354770 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 2 flagged: 353111 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 14 flagged: 352540 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 19 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 15 flagged: 511196 total: 2.51494e+06 (20.3%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          antenna 16 flagged: 507257 total: 2.51494e+06 (20.2%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          field 3C147 flagged: 9.61534e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          spw 0 flagged: 9.61534e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          correlation LL flagged: 4.81288e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          correlation RR flagged: 4.80246e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          array 0 flagged: 9.61534e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Summary::getResult          Total Flagged: 9.61534e+06 Total Counts: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:04:58.905323 End time: 2024-09-11 02:04:59.329035
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa         * Flagging 3ctst_copy.ms
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='rflag', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', field='0', antenna='', uvrange='', timerange='', correlation='', scan='', intent='', array='', observation='', feed='', clipminmax=[], datacolumn='data', clipoutside=True, channelavg=False, chanbin=1, timeavg=True, timebin='3min', clipzeros=False, quackinterval=1.0, quackmode='beg', quackincrement=False, tolerance=0.0, addantenna='', lowerlimit=0.0, upperlimit=90.0, ntime='scan', combinescans=True, timecutoff=4.0, freqcutoff=3.0, timefit='line', freqfit='poly', maxnpieces=7, flagdimension='freqtime', usewindowstats='none', halfwin=1, extendflags=False, winsize=3, timedev='', freqdev='', timedevscale=7.0, freqdevscale=7.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='none', flagbackup=False, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        Rflag mode is active
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        Parsing the display parameters
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        AgentFlagger::initAgents        There are initially 2 agents in the list. Agents: rflag, display
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters          data column is DATA
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters          Visibility expression is ABS RR,LL
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters         Time average bin is 180
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters         display is: none
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters         writeflags is: 1
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters         (writeflags OR display)=(1,none), will apply flags on modified flag cube
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters         winsize is 3
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters         spectralmax is 1e+06
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters         spectralmin is 0
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters         timedevscale is 7
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters         freqdevscale is 7
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters        No timedev value given. Will Use automatically computed values if applying flags.
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Rflag::setAgentParameters        No freqdev value given. Will Use automatically computed values if applying flags.
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Display::setAgentParameters          data column is DATA
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Display::setAgentParameters          Visibility expression is ABS RR,LL
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Display::setAgentParameters          pause is 1
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Display::setAgentParameters          datadisplay is 0
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Display::setAgentParameters          reportdisplay is 0
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        Display::setAgentParameters          format is screen
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        AgentFlagger::initAgents        There are 2 valid agents in the list. Agents: rflag, display
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:05:02 2024-09-11 02:04:59        INFO        FlagDataHandler::preLoadColumn         Setting row blocking to number of rows in selected table: 56985
11-Sep-2024 02:05:02 2024-09-11 02:05:01        INFO        FlagMSHandler::        Chunk = 1 [progress: 100%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 56985
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , 3C147 Spw : 0 Scan : 1 : 11
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        Rflag::chunkSummary        => Data flagged so far 0.000156293% (7/4478760)
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        Rflag::tableSummary        => Percentage of data flagged in table selection: 0.000156293%
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        AgentFlagger::run        => Writing flags to the MS
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        Display::displayReports         Report Displays are turned OFF
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:04:59.336623 End time: 2024-09-11 02:05:02.886985
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:02 2024-09-11 02:05:03        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='summary', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', 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 )
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        Summary mode is active
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::setAgentParameters          display is:
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        FlagMSHandler::        Chunk = 1 [progress: 53%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 30450
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        FlagMSHandler::        Chunk = 2 [progress: 100%], Observation = 0, Array = 0, Scan = 11, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 26535
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          observation 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          scan 11 flagged: 4.4775e+06 total: 1.75662e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          scan 1 flagged: 5.13785e+06 total: 2.01579e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 29 flagged: 669363 total: 2.51494e+06 (26.6%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 27 flagged: 584615 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 26 flagged: 584676 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 28 flagged: 670637 total: 2.51494e+06 (26.7%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 23 flagged: 336755 total: 2.51494e+06 (13.4%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 20 flagged: 350508 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 18 flagged: 584935 total: 2.51494e+06 (23.3%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 24 flagged: 332031 total: 2.51494e+06 (13.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 17 flagged: 609443 total: 2.51494e+06 (24.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 6 flagged: 347016 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 21 flagged: 344996 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 4 flagged: 370066 total: 2.51494e+06 (14.7%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 9 flagged: 356521 total: 2.51494e+06 (14.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 11 flagged: 341940 total: 2.51494e+06 (13.6%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 3 flagged: 348138 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 13 flagged: 350382 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 22 flagged: 338485 total: 2.51494e+06 (13.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 5 flagged: 344861 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 30 flagged: 674681 total: 2.51494e+06 (26.8%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 8 flagged: 364743 total: 2.51494e+06 (14.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 25 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 12 flagged: 348678 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 1 flagged: 353539 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 7 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 10 flagged: 354770 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 2 flagged: 353113 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 14 flagged: 352541 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 19 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 15 flagged: 511196 total: 2.51494e+06 (20.3%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          antenna 16 flagged: 507257 total: 2.51494e+06 (20.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          field 3C147 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          spw 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          correlation LL flagged: 4.81288e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          correlation RR flagged: 4.80247e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          array 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::getResult          Total Flagged: 9.61535e+06 Total Counts: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:05:02.894420 End time: 2024-09-11 02:05:03.362179
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa         * Step 5. Total flagged before: 9615343.0 (25.489%), after: 9615350.0 (25.489%). Used timebin: 3min, scale: 7
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='summary', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', 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 )
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        Summary mode is active
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        Summary::setAgentParameters          display is:
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:05:03 2024-09-11 02:05:03        INFO        FlagMSHandler::        Chunk = 1 [progress: 53%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 30450
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        FlagMSHandler::        Chunk = 2 [progress: 100%], Observation = 0, Array = 0, Scan = 11, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 26535
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          observation 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          scan 11 flagged: 4.4775e+06 total: 1.75662e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          scan 1 flagged: 5.13785e+06 total: 2.01579e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 29 flagged: 669363 total: 2.51494e+06 (26.6%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 27 flagged: 584615 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 26 flagged: 584676 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 28 flagged: 670637 total: 2.51494e+06 (26.7%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 23 flagged: 336755 total: 2.51494e+06 (13.4%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 20 flagged: 350508 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 18 flagged: 584935 total: 2.51494e+06 (23.3%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 24 flagged: 332031 total: 2.51494e+06 (13.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 17 flagged: 609443 total: 2.51494e+06 (24.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 6 flagged: 347016 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 21 flagged: 344996 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 4 flagged: 370066 total: 2.51494e+06 (14.7%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 9 flagged: 356521 total: 2.51494e+06 (14.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 11 flagged: 341940 total: 2.51494e+06 (13.6%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 3 flagged: 348138 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 13 flagged: 350382 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 22 flagged: 338485 total: 2.51494e+06 (13.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 5 flagged: 344861 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 30 flagged: 674681 total: 2.51494e+06 (26.8%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 8 flagged: 364743 total: 2.51494e+06 (14.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 25 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 12 flagged: 348678 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 1 flagged: 353539 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 7 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 10 flagged: 354770 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 2 flagged: 353113 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 14 flagged: 352541 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 19 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 15 flagged: 511196 total: 2.51494e+06 (20.3%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          antenna 16 flagged: 507257 total: 2.51494e+06 (20.2%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          field 3C147 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          spw 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          correlation LL flagged: 4.81288e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          correlation RR flagged: 4.80247e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          array 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        Summary::getResult          Total Flagged: 9.61535e+06 Total Counts: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:05:03.369793 End time: 2024-09-11 02:05:03.803247
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        flagdata::::casa         * Flagging 3ctst_copy.ms
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:03 2024-09-11 02:05:04        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='rflag', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', field='0', antenna='', uvrange='', timerange='', correlation='', scan='', intent='', array='', observation='', feed='', clipminmax=[], datacolumn='data', clipoutside=True, channelavg=False, chanbin=1, timeavg=True, timebin='3min', clipzeros=False, quackinterval=1.0, quackmode='beg', quackincrement=False, tolerance=0.0, addantenna='', lowerlimit=0.0, upperlimit=90.0, ntime='scan', combinescans=True, timecutoff=4.0, freqcutoff=3.0, timefit='line', freqfit='poly', maxnpieces=7, flagdimension='freqtime', usewindowstats='none', halfwin=1, extendflags=False, winsize=3, timedev='', freqdev='', timedevscale=7.0, freqdevscale=7.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='none', flagbackup=False, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        flagdata::::casa        Rflag mode is active
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        flagdata::::casa        Parsing the display parameters
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        AgentFlagger::initAgents        There are initially 2 agents in the list. Agents: rflag, display
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters          data column is DATA
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters          Visibility expression is ABS RR,LL
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters         Time average bin is 180
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters         display is: none
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters         writeflags is: 1
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters         (writeflags OR display)=(1,none), will apply flags on modified flag cube
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters         winsize is 3
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters         spectralmax is 1e+06
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters         spectralmin is 0
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters         timedevscale is 7
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters         freqdevscale is 7
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters        No timedev value given. Will Use automatically computed values if applying flags.
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Rflag::setAgentParameters        No freqdev value given. Will Use automatically computed values if applying flags.
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Display::setAgentParameters          data column is DATA
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Display::setAgentParameters          Visibility expression is ABS RR,LL
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Display::setAgentParameters          pause is 1
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Display::setAgentParameters          datadisplay is 0
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Display::setAgentParameters          reportdisplay is 0
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        Display::setAgentParameters          format is screen
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        AgentFlagger::initAgents        There are 2 valid agents in the list. Agents: rflag, display
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:05:07 2024-09-11 02:05:04        INFO        FlagDataHandler::preLoadColumn         Setting row blocking to number of rows in selected table: 56985
11-Sep-2024 02:05:07 2024-09-11 02:05:06        INFO        FlagMSHandler::        Chunk = 1 [progress: 100%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 56985
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , 3C147 Spw : 0 Scan : 1 : 11
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        Rflag::chunkSummary        => Data flagged so far 8.93104e-05% (4/4478760)
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        Rflag::tableSummary        => Percentage of data flagged in table selection: 8.93104e-05%
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        AgentFlagger::run        => Writing flags to the MS
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        Display::displayReports         Report Displays are turned OFF
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:05:03.810721 End time: 2024-09-11 02:05:07.389369
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='summary', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', 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 )
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        flagdata::::casa        Summary mode is active
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        Summary::setAgentParameters          display is:
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:05:07 2024-09-11 02:05:07        INFO        FlagMSHandler::        Chunk = 1 [progress: 53%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 30450
11-Sep-2024 02:05:07 2024-09-11 02:05:08        INFO        FlagMSHandler::        Chunk = 2 [progress: 100%], Observation = 0, Array = 0, Scan = 11, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 26535
11-Sep-2024 02:05:07 2024-09-11 02:05:08        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:05:07 2024-09-11 02:05:08        INFO        Summary::getResult          observation 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:07 2024-09-11 02:05:08        INFO        Summary::getResult          scan 11 flagged: 4.4775e+06 total: 1.75662e+07 (25.5%)
11-Sep-2024 02:05:07 2024-09-11 02:05:08        INFO        Summary::getResult          scan 1 flagged: 5.13785e+06 total: 2.01579e+07 (25.5%)
11-Sep-2024 02:05:07 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 29 flagged: 669363 total: 2.51494e+06 (26.6%)
11-Sep-2024 02:05:07 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 27 flagged: 584615 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:07 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 26 flagged: 584676 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 28 flagged: 670637 total: 2.51494e+06 (26.7%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 23 flagged: 336755 total: 2.51494e+06 (13.4%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 20 flagged: 350508 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 18 flagged: 584936 total: 2.51494e+06 (23.3%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 24 flagged: 332031 total: 2.51494e+06 (13.2%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 17 flagged: 609445 total: 2.51494e+06 (24.2%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 6 flagged: 347016 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 21 flagged: 344996 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 4 flagged: 370067 total: 2.51494e+06 (14.7%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 9 flagged: 356521 total: 2.51494e+06 (14.2%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 11 flagged: 341940 total: 2.51494e+06 (13.6%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 3 flagged: 348138 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 13 flagged: 350384 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 22 flagged: 338485 total: 2.51494e+06 (13.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 5 flagged: 344861 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 30 flagged: 674681 total: 2.51494e+06 (26.8%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 8 flagged: 364743 total: 2.51494e+06 (14.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 25 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 12 flagged: 348678 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 1 flagged: 353539 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 7 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 10 flagged: 354770 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 2 flagged: 353114 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 14 flagged: 352542 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 19 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 15 flagged: 511196 total: 2.51494e+06 (20.3%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 16 flagged: 507257 total: 2.51494e+06 (20.2%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          field 3C147 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          spw 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          correlation LL flagged: 4.81288e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          correlation RR flagged: 4.80247e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          array 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          Total Flagged: 9.61535e+06 Total Counts: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:05:07.396930 End time: 2024-09-11 02:05:07.836771
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa         * Step 6. Total flagged before: 9615350.0 (25.489%), after: 9615354.0 (25.489%). Used timebin: 3min, scale: 7
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='summary', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', 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 )
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        Summary mode is active
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::setAgentParameters          display is:
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        FlagMSHandler::        Chunk = 1 [progress: 53%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 30450
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        FlagMSHandler::        Chunk = 2 [progress: 100%], Observation = 0, Array = 0, Scan = 11, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 26535
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          observation 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          scan 11 flagged: 4.4775e+06 total: 1.75662e+07 (25.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          scan 1 flagged: 5.13785e+06 total: 2.01579e+07 (25.5%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 29 flagged: 669363 total: 2.51494e+06 (26.6%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 27 flagged: 584615 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 26 flagged: 584676 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 28 flagged: 670637 total: 2.51494e+06 (26.7%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 23 flagged: 336755 total: 2.51494e+06 (13.4%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 20 flagged: 350508 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 18 flagged: 584936 total: 2.51494e+06 (23.3%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 24 flagged: 332031 total: 2.51494e+06 (13.2%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 17 flagged: 609445 total: 2.51494e+06 (24.2%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 6 flagged: 347016 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 21 flagged: 344996 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 4 flagged: 370067 total: 2.51494e+06 (14.7%)
11-Sep-2024 02:05:08 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 9 flagged: 356521 total: 2.51494e+06 (14.2%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 11 flagged: 341940 total: 2.51494e+06 (13.6%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 3 flagged: 348138 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 13 flagged: 350384 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 22 flagged: 338485 total: 2.51494e+06 (13.5%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 5 flagged: 344861 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 30 flagged: 674681 total: 2.51494e+06 (26.8%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 8 flagged: 364743 total: 2.51494e+06 (14.5%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 25 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 12 flagged: 348678 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 1 flagged: 353539 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 7 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 10 flagged: 354770 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 2 flagged: 353114 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 14 flagged: 352542 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 19 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 15 flagged: 511196 total: 2.51494e+06 (20.3%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          antenna 16 flagged: 507257 total: 2.51494e+06 (20.2%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          field 3C147 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          spw 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          correlation LL flagged: 4.81288e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          correlation RR flagged: 4.80247e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          array 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Summary::getResult          Total Flagged: 9.61535e+06 Total Counts: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:05:07.844359 End time: 2024-09-11 02:05:08.270706
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa         * Flagging 3ctst_copy.ms
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='rflag', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', field='0', antenna='', uvrange='', timerange='', correlation='', scan='', intent='', array='', observation='', feed='', clipminmax=[], datacolumn='data', clipoutside=True, channelavg=False, chanbin=1, timeavg=True, timebin='3min', clipzeros=False, quackinterval=1.0, quackmode='beg', quackincrement=False, tolerance=0.0, addantenna='', lowerlimit=0.0, upperlimit=90.0, ntime='scan', combinescans=True, timecutoff=4.0, freqcutoff=3.0, timefit='line', freqfit='poly', maxnpieces=7, flagdimension='freqtime', usewindowstats='none', halfwin=1, extendflags=False, winsize=3, timedev='', freqdev='', timedevscale=7.0, freqdevscale=7.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='none', flagbackup=False, savepars=False, cmdreason='', outfile='', overwrite=True, writeflags=True )
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        Rflag mode is active
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        Parsing the display parameters
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        AgentFlagger::initAgents        There are initially 2 agents in the list. Agents: rflag, display
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters          data column is DATA
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters          Visibility expression is ABS RR,LL
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters         Time average bin is 180
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters         display is: none
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters         writeflags is: 1
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters         (writeflags OR display)=(1,none), will apply flags on modified flag cube
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters         winsize is 3
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters         spectralmax is 1e+06
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters         spectralmin is 0
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters         timedevscale is 7
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters         freqdevscale is 7
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters        No timedev value given. Will Use automatically computed values if applying flags.
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Rflag::setAgentParameters        No freqdev value given. Will Use automatically computed values if applying flags.
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Display::setAgentParameters          data column is DATA
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Display::setAgentParameters          Visibility expression is ABS RR,LL
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Display::setAgentParameters          pause is 1
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Display::setAgentParameters          datadisplay is 0
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Display::setAgentParameters          reportdisplay is 0
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        Display::setAgentParameters          format is screen
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        AgentFlagger::initAgents        There are 2 valid agents in the list. Agents: rflag, display
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:05:11 2024-09-11 02:05:08        INFO        FlagDataHandler::preLoadColumn         Setting row blocking to number of rows in selected table: 56985
11-Sep-2024 02:05:11 2024-09-11 02:05:10        INFO        FlagMSHandler::        Chunk = 1 [progress: 100%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 56985
11-Sep-2024 02:05:11 2024-09-11 02:05:12        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , 3C147 Spw : 0 Scan : 1 : 11
11-Sep-2024 02:05:11 2024-09-11 02:05:12        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:05:11 2024-09-11 02:05:12        INFO        AgentFlagger::run        => Writing flags to the MS
11-Sep-2024 02:05:11 2024-09-11 02:05:12        INFO        Display::displayReports         Report Displays are turned OFF
11-Sep-2024 02:05:11 2024-09-11 02:05:12        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:05:08.278400 End time: 2024-09-11 02:05:11.716106
11-Sep-2024 02:05:11 2024-09-11 02:05:12        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:05:11 2024-09-11 02:05:12        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:11 2024-09-11 02:05:12        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:11 2024-09-11 02:05:12        INFO        flagdata::::casa        ##### Begin Task: flagdata           #####
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        flagdata::::casa        flagdata( vis='3ctst_copy.ms', mode='summary', autocorr=False, inpfile='', reason='any', tbuff=0.0, spw='0:20~350', 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 )
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        AgentFlagger::open        Table type is Measurement Set
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        flagdata::::casa        Summary mode is active
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [1, 4]  (NB: Matrix in Row/Column order)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        FlagMSHandler::selectData +        [0, 20, 350, 1]
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        flagdata::::casa        Initializing the agents
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: summary
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::setAgentParameters          display is:
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: summary
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        flagdata::::casa        Running the agentflagger tool
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        FlagMSHandler::        Chunk = 1 [progress: 53%], Observation = 0, Array = 0, Scan = 1, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 30450
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        FlagMSHandler::        Chunk = 2 [progress: 100%], Observation = 0, Array = 0, Scan = 11, Field = 0 (3C147), Spw = 0, Channels = 429, Corrs = [ RR LL ], Total Rows = 26535
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        FlagMSHandler::nextChunk         ====================================================================================
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          observation 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          scan 11 flagged: 4.4775e+06 total: 1.75662e+07 (25.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          scan 1 flagged: 5.13785e+06 total: 2.01579e+07 (25.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 29 flagged: 669363 total: 2.51494e+06 (26.6%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 27 flagged: 584615 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 26 flagged: 584676 total: 2.51494e+06 (23.2%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 28 flagged: 670637 total: 2.51494e+06 (26.7%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 23 flagged: 336755 total: 2.51494e+06 (13.4%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 20 flagged: 350508 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 18 flagged: 584936 total: 2.51494e+06 (23.3%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 24 flagged: 332031 total: 2.51494e+06 (13.2%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 17 flagged: 609445 total: 2.51494e+06 (24.2%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 6 flagged: 347016 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 21 flagged: 344996 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 4 flagged: 370067 total: 2.51494e+06 (14.7%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 9 flagged: 356521 total: 2.51494e+06 (14.2%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 11 flagged: 341940 total: 2.51494e+06 (13.6%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 3 flagged: 348138 total: 2.51494e+06 (13.8%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 13 flagged: 350384 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 22 flagged: 338485 total: 2.51494e+06 (13.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 5 flagged: 344861 total: 2.51494e+06 (13.7%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 30 flagged: 674681 total: 2.51494e+06 (26.8%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 8 flagged: 364743 total: 2.51494e+06 (14.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 25 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 12 flagged: 348678 total: 2.51494e+06 (13.9%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 1 flagged: 353539 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 7 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 10 flagged: 354770 total: 2.51494e+06 (14.1%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 2 flagged: 353114 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 14 flagged: 352542 total: 2.51494e+06 (14%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 19 flagged: 2.51494e+06 total: 2.51494e+06 (100%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 15 flagged: 511196 total: 2.51494e+06 (20.3%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          antenna 16 flagged: 507257 total: 2.51494e+06 (20.2%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          field 3C147 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          spw 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          correlation LL flagged: 4.81288e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          correlation RR flagged: 4.80247e+06 total: 1.8862e+07 (25.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          array 0 flagged: 9.61535e+06 total: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        Summary::getResult          Total Flagged: 9.61535e+06 Total Counts: 3.77241e+07 (25.5%)
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        flagdata::::casa        Flags are not written to the MS. (action='calculate')
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        flagdata::::casa        Task flagdata complete. Start time: 2024-09-11 02:05:11.723550 End time: 2024-09-11 02:05:12.145709
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        flagdata::::casa        ##### End Task: flagdata             #####
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        flagdata::::casa        ##########################################
11-Sep-2024 02:05:12 2024-09-11 02:05:12        INFO        flagdata::::casa         * Step 7. Total flagged before: 9615354.0 (25.489%), after: 9615354.0 (25.489%). Used timebin: 3min, scale: 7
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Step 2
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Time now: 1726020312.1459768
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Time used this step: 31.110451698303223
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Total time used so far: 61.80218768119812
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Step  Time used (s)     Fraction of total time (percent) [description]
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa          2   25.33903479576111  41.00022304464462 [2]
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa          2   5.352701187133789  8.661022187022391 [2]
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa          2   31.110451698303223  50.338754768333 [2]
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Step 3
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Time now: 1726020312.146249
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Time used this step: 0.0002722740173339844
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Total time used so far: 61.802459955215454
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa        Step  Time used (s)     Fraction of total time (percent) [description]
11-Sep-2024 02:05:12 2024-09-11 02:05:12        WARN        TIMING::::casa          3   25.33903479576111  41.000042415986016 [3]
11-Sep-2024 02:05:15 2024-09-11 02:05:12        WARN        TIMING::::casa          3   5.352701187133789  8.660984030429487 [3]
11-Sep-2024 02:05:15 2024-09-11 02:05:12        WARN        TIMING::::casa          3   31.110451698303223  50.338532998277266 [3]
11-Sep-2024 02:05:15 2024-09-11 02:05:12        WARN        TIMING::::casa          3   0.0002722740173339844  0.00044055530723418625 [3]
11-Sep-2024 02:05:15
11-Sep-2024 02:05:15 Using user configuration file /home/casatest/.casa/config.py
11-Sep-2024 02:05:15
11-Sep-2024 02:05:15 Using matplotlib backend: TkAgg
11-Sep-2024 02:05:15 CASA 6.7.0.1 -- Common Astronomy Software Applications [6.7.0.1]
11-Sep-2024 02:05:15 .
11-Sep-2024 02:05:15 ----------------------------------------------------------------------
11-Sep-2024 02:05:15 Ran 2 tests in 62.385s
11-Sep-2024 02:05:15
11-Sep-2024 02:05:15 OK
11-Sep-2024 02:05:15 Executor result: 0
11-Sep-2024 02:05:15 Failed tests:[]
11-Sep-2024 02:05:15
11-Sep-2024 02:05:15 Running test: test_regression_alma_sim_componentlist in Serial mode
11-Sep-2024 02:05:15 Running cmd ['/home/casatest/work/pkg/casa-6.7.0-1-py3.8.el8/bin/casa', '--nogui', '--nologger', '--log2term', '-c', '/home/casatest/work/casasources//casa6/casatests/regression/test_regression_alma_sim_componentlist.py']in /home/casatest/work/test_regression_alma_sim_componentlist
11-Sep-2024 02:05:15 Executing: ['/home/casatest/work/pkg/casa-6.7.0-1-py3.8.el8/bin/casa', '--nogui', '--nologger', '--log2term', '-c', '/home/casatest/work/casasources//casa6/casatests/regression/test_regression_alma_sim_componentlist.py']
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        Using user configuration file /home/casatest/.casa/config.py
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        loaded config file : /home/casatest/work/pkg/casa-6.7.0-1-py3.8.el8/lib/py/lib/python3.8/site-packages/casaconfig/config.py
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        loaded config file : /home/casatest/.casa/config.py
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        config values
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            agg = False
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            cachedir = '/home/casatest/.casa'
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            casaconfig_verbose = 1
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            colors = 'Neutral'
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            data_auto_update = False
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            datapath = ['/opt/casa/data/casa-data', '/opt/casa/data/master', '/opt/casa/data/casatestdata']
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            iplog = False
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            iplogfile = '/home/casatest/work/test_regression_alma_sim_componentlist/ipython-20240911-020513.log'
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            log2term = True
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            logfile = '/home/casatest/work/test_regression_alma_sim_componentlist/casa-20240911-020513.log'
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            measures_auto_update = False
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            measurespath = '/opt/casa/data/casa-data'
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            nogui = True
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            nologfile = False
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            nologger = True
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            pipeline = False
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            startupfile = '/home/casatest/.casa/startup.py'
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa            user_site = False
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        casarundata version is unknown, this is probably a legacy version not installed by casaconfig
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        measures version is unknown, this is probably a legacy version not installed by casaconfig
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa        Checking Measures tables in data repository sub-directory /opt/casa/data/casa-data/geodetic
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa          IERSeop2000 (version date, last date in table (UTC)): 2024/03/09/15:00, 2024/02/08/00:00:00
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa          IERSeop97 (version date, last date in table (UTC)): 2024/03/09/15:00, 2024/02/08/00:00:00
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa          IERSpredict (version date, last date in table (UTC)): 2024/03/22/15:00, 2024/06/20/00:00:00
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ::casa          TAI_UTC (version date, last date in table (UTC)): 2024/03/21/15:00, 2017/01/01/00:00:00
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        ImageFactory::createImage        Created Paged image 'Gaussian.im' of shape [256, 256, 1, 1] with float valued pixels.
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        exportfits::::casa        ##########################################
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        exportfits::::casa        ##### Begin Task: exportfits         #####
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        exportfits::::casa        exportfits( imagename='Gaussian.im', fitsimage='Gaussian.fits', velocity=False, optical=False, bitpix=-32, minpix=0, maxpix=-1, overwrite=True, dropstokes=False, stokeslast=True, history=True, dropdeg=False )
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        exportfits::::casa        Task exportfits complete. Start time: 2024-09-11 02:05:14.875202 End time: 2024-09-11 02:05:14.880028
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        exportfits::::casa        ##### End Task: exportfits           #####
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        exportfits::::casa        ##########################################
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::::casa        ##########################################
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::::casa        ##### Begin Task: simobserve         #####
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::::casa        simobserve( project='Image_list', skymodel='Gaussian.fits', inbright='', indirection='', incell='', incenter='', inwidth='1GHz', complist='point.cl', compwidth='1GHz', comp_nchan=1, setpointings=True, ptgfile='$project.ptg.txt', integration='10s', direction=['J2000 10h00m00.0s -30d00m00.0s'], mapsize=['', ''], maptype='ALMA', pointingspacing='', caldirection='', calflux='1Jy', obsmode='int', refdate='2014/05/21', hourangle='transit', totaltime='28800s', antennalist='alma.cycle6.1.cfg', sdantlist='aca.tp.cfg', sdant=0, outframe='LSRK', thermalnoise='', user_pwv=0.5, t_ground=270.0, t_sky=260.0, tau0=0.1, seed=11111, leakage=0.0, graphics='both', verbose=False, overwrite=True )
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::ImageFactory::createImage        Created Paged image 'Image_list/Image_list.alma.cycle6.1.skymodel' of shape [256, 256, 1, 1] with float valued pixels.
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::ImageFactory::fromImage        Created Paged image 'Image_list/Image_list.alma.cycle6.1.skymodel.flat' of shape [256, 256] with float valued pixels.
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::image::_adddegaxes         Creating image 'Image_list/Image_list.alma.cycle6.1.skymodel.flat' of shape [256, 256, 1]
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::ComponentListImage::_computePointSourcePixelValues        Found 4 of 4 point sources located within the image and cached their pixel coordinates.
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::readantenna::casa        Reading antenna positions from '/opt/casa/data/casa-data/alma/simmos/alma.cycle6.1.cfg'
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::readantenna::casa        Reading antenna positions from '/opt/casa/data/casa-data/alma/simmos/alma.cycle6.1.cfg'
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::simobserve::casa        calculating map pointings centered at J2000 10h00m00.0s -30d00m00.0s
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::calc_pointings::casa        using 7 generated pointing(s)
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::simobserve::casa        center = J2000 10:00:00.00000 -030.00.00.000000
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:05:15 2024-09-11 02:05:15        INFO        simobserve::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:05:15 2024-09-11 02:05:16        INFO        simobserve::simobserve::casa        preparing empty measurement set
11-Sep-2024 02:05:15 2024-09-11 02:05:16        INFO        simobserve::simobserve::casa        You desire an hour angle of 0.0 hours
11-Sep-2024 02:05:15 2024-09-11 02:05:16        INFO        simobserve::NewMSSimulator::initAnt()         Using global coordinates for the antennas
11-Sep-2024 02:05:15 2024-09-11 02:05:16        INFO        simobserve::NewMSSimulator::initAnt()         Added rows to ANTENNA table
11-Sep-2024 02:05:15 2024-09-11 02:05:16        INFO        simobserve::MSsimulator::initSpWindows()         Creating new spectral window band2, ID 1
11-Sep-2024 02:05:15 2024-09-11 02:05:16        INFO        simobserve::MSsimulator::initFeeds()         Added rows to FEED table
11-Sep-2024 02:05:15 2024-09-11 02:05:16        INFO        simobserve::simulator::settimes()        Times
11-Sep-2024 02:05:15 2024-09-11 02:05:16        INFO        simobserve::simulator::settimes()+             Integration time 10s
11-Sep-2024 02:05:15 2024-09-11 02:05:16        INFO        simobserve::simulator::settimes()             Times will be interpreted as hour angles for first source
11-Sep-2024 02:08:14 2024-09-11 02:05:16        INFO        simobserve::NewMSSimulator::observe()        First source: Image_list.alma.cycle6.1_0 @ 09:59:59.14   -30.00.06.47       J2000
11-Sep-2024 02:08:14 2024-09-11 02:05:16        INFO        simobserve::NewMSSimulator::observe()+        Full time range: 20-May-2014/18:38:00.4 -- 21-May-2014/02:38:00.4 TAI with int = 10
11-Sep-2024 02:08:14 2024-09-11 02:05:16        INFO        simobserve::NewMSSimulator::observe()        Calculating a total of 2880 integrations
11-Sep-2024 02:08:14 2024-09-11 02:05:40        INFO        simobserve::Simulator::setdata()         Selecting data
11-Sep-2024 02:08:14 2024-09-11 02:05:40        INFO        simobserve::Simulator::setdata()         Performing selection on MeasurementSet
11-Sep-2024 02:08:14 2024-09-11 02:05:40        INFO        simobserve::Simulator::setdata()         Selecting on field and spectral window ids
11-Sep-2024 02:08:14 2024-09-11 02:05:40        INFO        simobserve::Simulator::setdata()         No selection string given
11-Sep-2024 02:08:14 2024-09-11 02:05:40        INFO        simobserve::Simulator::setdata()         Selection did not drop any rows
11-Sep-2024 02:08:14 2024-09-11 02:05:41        INFO        simobserve::Simulator::setvp()        Setting voltage pattern parameters
11-Sep-2024 02:08:14 2024-09-11 02:05:41        INFO        simobserve::Simulator::setvp()        Beam Squint will be included in the VP model
11-Sep-2024 02:08:14 2024-09-11 02:05:41        INFO        simobserve::Simulator::setvp()        and the parallactic angle increment is 360 degrees
11-Sep-2024 02:08:14 2024-09-11 02:05:41        INFO        simobserve::simobserve::casa        done setting up observations (blank visibilities)
11-Sep-2024 02:08:14 2024-09-11 02:05:41        INFO        simobserve::simobserve::casa        predicting from Image_list/Image_list.alma.cycle6.1.skymodel and point.cl
11-Sep-2024 02:08:14 2024-09-11 02:05:41        INFO        simobserve::Simulator::predict()         Predicting visibilities using model: [Image_list/Image_list.alma.cycle6.1.skymodel] and componentList: point.cl
11-Sep-2024 02:08:14 2024-09-11 02:05:41        INFO        simobserve::Simulator::predict()         The data column will be replaced
11-Sep-2024 02:08:14 2024-09-11 02:05:41        INFO        simobserve::Simulator::createSkyEquation()         Opening model 0 named Image_list/Image_list.alma.cycle6.1.skymodel
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::Simulator::getVPRecord         Using Voltage Patterns from the VPManager
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::Simulator::createSkyEquation()         Synthesis gridding
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::Simulator::createSkyEquation()         Fourier transforms will use image centers as tangent points
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::VPSkyJones::VPSkyJones        PB used ALMA
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::BeamSkyJones::summary        Beam Summary:
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::BeamSkyJones::summary        Model 1 for ALMA ant=-1 feed=-1 uses PB: AIRY
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::PBMath1D::summary        Using AIRY PB Class
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::PBMathInterface::summary        These parameters are for Voltage Pattern
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::PBMathInterface::summary        No Beam Squint
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::PBMath1D::summary        Max Radius at 1 GHz: 107.04 arcmin
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::PBMath1DAiry::summary           Dish Diameter: 10.7 m
11-Sep-2024 02:08:14 2024-09-11 02:05:43        INFO        simobserve::PBMath1DAiry::summary        Blocked Diameter: 0.75 m
11-Sep-2024 02:08:14 2024-09-11 02:06:11        INFO        simobserve::Simulator::reset()         Resetting all visibility corruption components
11-Sep-2024 02:08:14 2024-09-11 02:06:11        INFO        simobserve::Simulator::reset()         Reset all image-plane corruption components
11-Sep-2024 02:08:14 2024-09-11 02:06:11        INFO        simobserve::simobserve::casa        generation of measurement set Image_list/Image_list.alma.cycle6.1.ms complete
11-Sep-2024 02:08:14 2024-09-11 02:06:12        INFO        simobserve::::        LAST of rise= 03:04:03.65
11-Sep-2024 02:08:14 2024-09-11 02:06:12        INFO        simobserve::::        LAST of  set= 16:57:15.00
11-Sep-2024 02:08:14 2024-09-11 02:06:12        INFO        simobserve::::        UTC of rise= 2014/05/20/15:42:12
11-Sep-2024 02:08:14 2024-09-11 02:06:12        INFO        simobserve::::+        UTC of  set= 2014/05/21/05:33:07
11-Sep-2024 02:08:14 2024-09-11 02:06:12        INFO        simobserve::ephemeris::casa        peak=20-May-2014/22:38:01
11-Sep-2024 02:08:14 2024-09-11 02:06:12        INFO        simobserve::Imager::open()         Opening MeasurementSet /home/casatest/work/test_regression_alma_sim_componentlist/Image_list/Image_list.alma.cycle6.1.ms
11-Sep-2024 02:08:14 2024-09-11 02:06:14        INFO        simobserve::simobserve::casa        using default model cell {'unit': 'arcsec', 'value': 0.10000000000000799} for PSF calculation
11-Sep-2024 02:08:14 2024-09-11 02:06:14        INFO        simobserve::imager::defineimage()        Defining image properties:nx=128 ny=128 cellx='0.1arcsec' celly='0.1arcsec' stokes=I' mode=MFS nchan=-1 start=0 step=0 spwids=[0] fieldid=0 facets=1 frame=1 distance='0'
11-Sep-2024 02:08:14 2024-09-11 02:06:14        INFO        simobserve::imager::defineimage()         phaseCenter='field-0 ' mStart='Radialvelocity: 0' qStep='0 '' mFreqStart='Frequency: 0
11-Sep-2024 02:08:14 2024-09-11 02:06:14        INFO        simobserve::imager::approximatepsfs()         Calculating approximate PSFs using full sky equation
11-Sep-2024 02:08:14 2024-09-11 02:06:15        INFO        simobserve::imager::createFTMachine()         Performing interferometric gridding...
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::ImageSkyModel::makeApproxPSFs        bmaj: 2.02616", bmin: 1.56826", bpa: -88.1084 deg
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::imager::approximatepsfs()         Approximate PSF  : size 2.02616 by 1.56826 (arcsec) at pa -88.1084 (deg)
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::imager::approximatepsfs() +        and volume = 164.218 pixels
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::imager::fitpsf()         Fitting to psf
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::imager::fitpsf()           Beam fit: 2.02616 by 1.56826 (arcsec) at pa -88.1084 (deg)
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::CasacRegionManager::fromBCS        Using all spectral channels.
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::CasacRegionManager::fromBCS        Using polarizations ALL
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::ImageRegion::fromRecord+            [0, 0, 0, 0] to [127, 127, 0, 0]  (09:59:59.630, -30.00.12.868, I, 2.3e+11Hz to 09:59:58.653, -30.00.00.168, I, 2.3e+11Hz)
11-Sep-2024 02:08:14 2024-09-11 02:06:19        INFO        simobserve::ImageCollapser::_doDegenerateAxesCase        All subimage axes to be collapsed are degenerate, using algorithm optimized for that case.
11-Sep-2024 02:08:14 2024-09-11 02:06:20        INFO        simobserve::::casa        Task simobserve complete. Start time: 2024-09-11 02:05:14.935350 End time: 2024-09-11 02:06:19.968838
11-Sep-2024 02:08:14 2024-09-11 02:06:20        INFO        simobserve::::casa        ##### End Task: simobserve           #####
11-Sep-2024 02:08:14 2024-09-11 02:06:20        INFO        simobserve::::casa        ##########################################
11-Sep-2024 02:08:14 2024-09-11 02:06:20        INFO        simanalyze::::casa        ##########################################
11-Sep-2024 02:08:14 2024-09-11 02:06:20        INFO        simanalyze::::casa        ##### Begin Task: simanalyze         #####
11-Sep-2024 02:08:14 2024-09-11 02:06:20        INFO        simanalyze::::casa        simanalyze( project='Image_list', image=True, imagename='default', skymodel='', vis='default', modelimage='', imsize=[256, 256], imdirection='J2000 10h00m00.0s -30d00m00.0s', cell='0.1arcsec', interactive=False, niter=5000, threshold='10.0mJy/beam', weighting='natural', mask=[], outertaper=[], pbcor=True, stokes='I', featherimage='', analyze=True, showuv=True, showpsf=True, showmodel=True, showconvolved=False, showclean=True, showresidual=False, showdifference=True, showfidelity=True, graphics='both', verbose=False, overwrite=True, dryrun=False, logfile='' )
11-Sep-2024 02:08:14 2024-09-11 02:06:20        INFO        simanalyze::::casa        saveinputs not available in casatasks, skipping saving simanalyze inputs
11-Sep-2024 02:08:14 2024-09-11 02:06:20        INFO        simanalyze::simanalyze::casa        Found a synthesis measurement set, Image_list/Image_list.alma.cycle6.1.ms.
11-Sep-2024 02:08:14 2024-09-11 02:06:20        INFO        simanalyze::simanalyze::casa        Sky model image Image_list/Image_list.alma.cycle6.1.skymodel found.
11-Sep-2024 02:08:14 2024-09-11 02:06:21        INFO        simanalyze::simutil::casa        tclean( vis='Image_list/Image_list.alma.cycle6.1.ms',selectdata=False,imagename='Image_list/Image_list.alma.cycle6.1',imsize=[256, 256],cell=['0.1arcsec', '0.1arcsec'],phasecenter='J2000 10h00m00.0s -30d00m00.0s',stokes='I',startmodel='',specmode='mfs',gridder='mosaic',deconvolver='clark',restoration=True,outlierfile='',weighting='natural',niter=5000,usemask='user',fastnoise=True,restart=True,savemodel='none',calcres=True,calcpsf=True,parallel=False,restoringbeam='common',pbcor=True,uvtaper=[],threshold='10.0mJy/beam',interactive=False,mask=[],pbmask=0.0 )
11-Sep-2024 02:08:14
11-Sep-2024 02:08:14 0%....10....20....30....40....50....60....70....80....90....100%
11-Sep-2024 02:08:14
11-Sep-2024 02:08:14 0%....10....20....30....40....50....60....70....80....90....100%
11-Sep-2024 02:08:14
11-Sep-2024 02:08:14 0%....10....20....30....40....50....60....70....80....90....100%
11-Sep-2024 02:08:14
11-Sep-2024 02:08:14 0%....10....20....30....40....50....60....70....80....90....100%
11-Sep-2024 02:08:14
11-Sep-2024 02:08:14 0%....10....20....30....40....50....60....70....80....90....100%
11-Sep-2024 02:08:14
11-Sep-2024 02:08:18 0%....10....20....30....40....50....60....70....80....90....100%
11-Sep-2024 02:08:18 2024-09-11 02:08:15        INFO        ImageFactory::fromImage        Created Paged image 'Image_list/Image_list.alma.cycle6.1.image.flat' of shape [256, 256] with float valued pixels.
11-Sep-2024 02:08:18 2024-09-11 02:08:15        INFO        image::_adddegaxes         Creating image 'Image_list/Image_list.alma.cycle6.1.image.flat' of shape [256, 256, 1]
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        ImageFactory::fromImage        Created Paged image 'Image_list/Image_list.alma.cycle6.1.residual.flat' of shape [256, 256] with float valued pixels.
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        image::_adddegaxes         Creating image 'Image_list/Image_list.alma.cycle6.1.residual.flat' of shape [256, 256, 1]
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        tclean::simanalyze::casa        done inverting and cleaning
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        tclean::simanalyze::casa        synthesized beam area in output pixels = 361.375085
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::image::_setrestoringbeam         Will use dictionary specified in beam parameter to set the beam(s)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::BeamManipulator::set        Setting (global) restoring beam.
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::BeamManipulator::set        Beam parameters:  Major          : 2.03799 arcsec
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::BeamManipulator::set+          Minor          : 1.5649 arcsec
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::BeamManipulator::set+          Position Angle : -88.1133 deg
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageMaskAttacher::makeMask        Created and initialized mask `mask0'
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegrid::_checkAxes         The Stokes axis cannot be regridded - removing from list
11-Sep-2024 02:08:18 2024-09-11 02:08:16        WARN        simanalyze::ImageRegrid::regrid        You are regridding an image whose beam is not well sampled by the pixel size.  Total flux can be lost when regridding such images, especially when the new pixel size is larger than the old pixel size. It is recommended to check the total flux of your input and output image, and if necessary rebin the input to have smaller pixels.
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::SubImageFactory::createImage        Creating image 'Image_list/Image_list.alma.cycle6.1.skymodel.flat.regrid.tmp' of shape [256, 256, 1]
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageExprCalculator::_imagecalc        Creating image `Image_list/Image_list.alma.cycle6.1.skymodel.flat.regrid' of shape [256, 256, 1]
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::Image2DConvolver::_dealWithRestoringBeam        You are convolving the sky
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::Image2DConvolver::_dealWithRestoringBeam        Scaling pixel values by 1
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::Image2DConvolver::_dealWithRestoringBeam        Original /home/casatest/work/test_regression_alma_sim_componentlist/Image_list/Image_list.alma.cycle6.1.skymodel.flat.regrid resolution major: 2.03799 arcsec, minor: 1.5649 arcsec, pa: -88.1133 deg
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::Image2DConvolver::_dealWithRestoringBeam        Output Image_list/Image_list.alma.cycle6.1.skymodel.flat.regrid.conv resolution major: 2.03799 arcsec, minor: 1.5649 arcsec, pa: -88.1133 deg
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::SubImageFactory::createImage        Creating image 'Image_list/Image_list.alma.cycle6.1.skymodel.flat.regrid.conv' of shape [256, 256, 1]
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::image::_setrestoringbeam         Will use dictionary specified in beam parameter to set the beam(s)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::BeamManipulator::set        Setting (global) restoring beam.
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::BeamManipulator::set        Beam parameters:  Major          : 2.03799 arcsec
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::BeamManipulator::set+          Minor          : 1.5649 arcsec
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::BeamManipulator::set+          Position Angle : -88.1133 deg
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO1        simanalyze::ImageHistogramsCalculator::compute        Creating new statistics storage lattice of shape [13]
11-Sep-2024 02:08:18 2024-09-11 02:08:16        DEBUG1        simanalyze::ImageHistogramsCalculator::compute        Creating new histogram storage lattice
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO1        simanalyze::ImageHistogramsCalculator::compute        Creating new statistics storage lattice of shape [13]
11-Sep-2024 02:08:18 2024-09-11 02:08:16        DEBUG1        simanalyze::ImageHistogramsCalculator::compute        Creating new histogram storage lattice
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        simanalyze::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO1        simanalyze::ImageHistogramsCalculator::compute        Creating new statistics storage lattice of shape [13]
11-Sep-2024 02:08:18 2024-09-11 02:08:16        DEBUG1        simanalyze::ImageHistogramsCalculator::compute        Creating new histogram storage lattice
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        tclean::statim::casa        Image_list/Image_list.alma.cycle6.1.residual.flat: unknown units
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        statim::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        statim::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        statim::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO        statim::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:18 2024-09-11 02:08:16        INFO1        statim::ImageHistogramsCalculator::compute        Creating new statistics storage lattice of shape [13]
11-Sep-2024 02:08:18 2024-09-11 02:08:17        DEBUG1        statim::ImageHistogramsCalculator::compute        Creating new histogram storage lattice
11-Sep-2024 02:08:18 2024-09-11 02:08:17        INFO        statim::ImageExprCalculator::_imagecalc        Creating image `Image_list/Image_list.alma.cycle6.1.diff' of shape [256, 256, 1]
11-Sep-2024 02:08:18 2024-09-11 02:08:17        INFO        statim::ImageExprCalculator::_imagecalc        Creating image `Image_list/Image_list.alma.cycle6.1.absdiff' of shape [256, 256, 1]
11-Sep-2024 02:08:18 2024-09-11 02:08:17        INFO        statim::ImageExprCalculator::_imagecalc        Creating image `Image_list/Image_list.alma.cycle6.1.fidelity' of shape [256, 256, 1]
11-Sep-2024 02:08:18 2024-09-11 02:08:17        INFO        tclean::analysis::casa        fidelity image calculated
11-Sep-2024 02:08:18 2024-09-11 02:08:17        INFO        analysis::ImageExprCalculator::_imagecalc        Creating image `Image_list/Image_list.alma.cycle6.1.absconv' of shape [256, 256, 1]
11-Sep-2024 02:08:18 2024-09-11 02:08:17        INFO        tclean::analysis::casa        fidelity range (max model / rms difference) = 129.49682866431715
11-Sep-2024 02:08:18 2024-09-11 02:08:18        INFO        analysis::CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
11-Sep-2024 02:08:18 2024-09-11 02:08:18        INFO        analysis::CasacRegionManager::fromBCS        Using all spectral channels.
11-Sep-2024 02:08:18 2024-09-11 02:08:18        INFO        analysis::CasacRegionManager::fromBCS        Using polarizations ALL
11-Sep-2024 02:08:18 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:18 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0, 0] to [255, 255, 0, 0]  (10:00:00.985, -30.00.12.800, I, 2.3e+11Hz to 09:59:59.022, -29.59.47.300, I, 2.3e+11Hz)
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageCollapser::_doDegenerateAxesCase        All subimage axes to be collapsed are degenerate, using algorithm optimized for that case.
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO1        analysis::ImageHistogramsCalculator::compute        Creating new statistics storage lattice of shape [13]
11-Sep-2024 02:08:20 2024-09-11 02:08:18        DEBUG1        analysis::ImageHistogramsCalculator::compute        Creating new histogram storage lattice
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO1        analysis::ImageHistogramsCalculator::compute        Creating new statistics storage lattice of shape [13]
11-Sep-2024 02:08:20 2024-09-11 02:08:18        DEBUG1        analysis::ImageHistogramsCalculator::compute        Creating new histogram storage lattice
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:18        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO1        analysis::ImageHistogramsCalculator::compute        Creating new statistics storage lattice of shape [13]
11-Sep-2024 02:08:20 2024-09-11 02:08:19        DEBUG1        analysis::ImageHistogramsCalculator::compute        Creating new histogram storage lattice
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO1        analysis::ImageHistogramsCalculator::compute        Creating new statistics storage lattice of shape [13]
11-Sep-2024 02:08:20 2024-09-11 02:08:19        DEBUG1        analysis::ImageHistogramsCalculator::compute        Creating new histogram storage lattice
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::ImageRegion::fromRecord        Selected bounding box :
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::ImageRegion::fromRecord+            [0, 0, 0] to [255, 255, 0]  (10:00:00.985, -30.00.12.800, I to 09:59:59.022, -29.59.47.300, I)
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO1        analysis::ImageHistogramsCalculator::compute        Creating new statistics storage lattice of shape [13]
11-Sep-2024 02:08:20 2024-09-11 02:08:19        DEBUG1        analysis::ImageHistogramsCalculator::compute        Creating new histogram storage lattice
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        tclean::analysis::casa        Simulation rms: [2.72510382e-06] Jy/pix = [0.00098478] Jy/bm
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        tclean::analysis::casa        Simulation max: [0.00035414] Jy/pix = [0.12797569] Jy/bm
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        tclean::analysis::casa        Beam bmaj: 2.037991523742676 bmin: 1.5649036169052124 bpa: -88.11333465576172
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::name::        No table opened.
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        analysis::name::        No table opened.
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        simanalyze::::casa        Task simanalyze complete. Start time: 2024-09-11 02:06:19.981904 End time: 2024-09-11 02:08:19.495905
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        simanalyze::::casa        ##### End Task: simanalyze           #####
11-Sep-2024 02:08:20 2024-09-11 02:08:19        INFO        simanalyze::::casa        ##########################################
11-Sep-2024 02:08:20 /home/casatest/work/pkg/casa-6.7.0-1-py3.8.el8/lib/py/lib/python3.8/asyncio/base_events.py:654: ResourceWarning: unclosed event loop <_UnixSelectorEventLoop running=False closed=False debug=False>
11-Sep-2024 02:08:20   _warn(f"unclosed event loop {self!r}", ResourceWarning, source=self)
11-Sep-2024 02:08:20 ResourceWarning: Enable tracemalloc to get the object allocation traceback
11-Sep-2024 02:08:20
11-Sep-2024 02:08:20 Using user configuration file /home/casatest/.casa/config.py
11-Sep-2024 02:08:20
11-Sep-2024 02:08:20 Using matplotlib backend: TkAgg
11-Sep-2024 02:08:20 CASA 6.7.0.1 -- Common Astronomy Software Applications [6.7.0.1]
11-Sep-2024 02:08:20 .
11-Sep-2024 02:08:20 ----------------------------------------------------------------------
11-Sep-2024 02:08:20 Ran 1 test in 184.885s
11-Sep-2024 02:08:20
11-Sep-2024 02:08:20 OK
11-Sep-2024 02:08:20 Executor result: 0
11-Sep-2024 02:08:20 Failed tests:[]
11-Sep-2024 02:08:20
11-Sep-2024 02:08:20 Results: [{'testname': 'test_regression_alma_M100_sd', 'runtime': '0:02:58.642146', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_fits_import_export', 'runtime': '0:00:11.653931', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_sim_TP', 'runtime': '0:00:39.001275', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_sim_components', 'runtime': '0:00:30.998312', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_sim_components_and_skymodel', 'runtime': '0:00:13.890917', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_sim_cube', 'runtime': '0:00:22.065354', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_sim_multi_arrays_and_TP', 'runtime': '0:00:57.217083', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_simalma_12m_ACA_combination', 'runtime': '0:01:01.526989', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_alma_M100_if', 'runtime': '0:17:44.073184', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_alma_M100_if', 'runtime': '0:21:30.940307', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_tclean_startmodel_restart', 'runtime': '0:07:20.816700', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_tclean_startmodel_restart', 'runtime': '0:00:01.881345', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_timeaverage_and_rflag', 'runtime': '0:01:04.341197', 'returncode': 0, 'testerr': []}, {'testname': 'test_regression_alma_sim_componentlist', 'runtime': '0:03:06.805343', 'returncode': 0, 'testerr': []}]
11-Sep-2024 02:08:20 Stopping Xvfb.
11-Sep-2024 02:08:20 Stopping framebuffer <Popen: returncode: None args: ['Xvfb', ':81', '-screen', '0', '2048x2048x24...>
11-Sep-2024 02:08:20 Virtual frame buffer deployed at :81 with pid 92 successfully shutdown
11-Sep-2024 02:08:20 Xvfb stopped.
11-Sep-2024 02:08:21 find: './proc/92/task/92/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/92/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/276/task/276/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/276/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/278/task/278/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/278/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/4970/task/4970/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/4970/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/4975/task/4975/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/4975/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/4976/task/4976/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/4976/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5095/task/5095/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5095/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5097/task/5097/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5097/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5392/task/5392/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5392/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5399/task/5399/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5399/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5401/task/5401/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/5401/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/6166/task/6166/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/6166/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/6168/task/6168/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/6168/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/50365/task/50365/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/50365/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/50373/task/50373/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/50373/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/50375/task/50375/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/50375/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/115587/task/115587/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/115587/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/115588/task/115588/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/115588/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/115590/task/115590/net': Invalid argument
11-Sep-2024 02:08:21 find: './proc/115590/net': Invalid argument
11-Sep-2024 02:08:22 total 980624
11-Sep-2024 02:08:22 -rw-r--r-- 1 casaci    15001 1004152988 Sep 11 01:09 casa-6.7.0-1-py3.8.el8.tar.xz
11-Sep-2024 02:08:22 drwxr-xr-x 2 casaci casatest        182 Sep 11 02:08 png_list
11-Sep-2024 02:08:22 -rw-r--r-- 1 casaci casatest       2439 Sep 11 02:08 xUnit-suite-mpi-4.xml
11-Sep-2024 02:08:22 Finished task 'Run casatests/regression scripts using a casalith' with result: Success
11-Sep-2024 02:08:22 Starting task 'Parse test results' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
11-Sep-2024 02:08:22 Parsing test results under /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMTC-CRR85D...
11-Sep-2024 02:08:22 Finished task 'Parse test results' with result: Success
11-Sep-2024 02:08:22 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
11-Sep-2024 02:08:22
Beginning to execute external process for build 'CASA - CMake Master Test Casalith - Regressions Rocky 8.5 Docker #107 (CASA-CMTC-CRR85D-107)'
... running command line:
/export/cvpost134-nvme/casa/bamboohome/temp/CASA-CMTC-CRR85D-107-ScriptBuildTask-904439953584513728.sh
... in: /export/cvpost134-nvme/casa/bamboohome/xml-data/build-dir/CASA-CMTC-CRR85D
11-Sep-2024 02:08:22 Starting cleanup
11-Sep-2024 02:08:32 test-container
11-Sep-2024 02:08:42 test-container
11-Sep-2024 02:08:42 Cleanup complete
11-Sep-2024 02:08:42 Finished task 'Cleanup' with result: Success
11-Sep-2024 02:08:42 Running post build plugin 'Docker Container Cleanup'
11-Sep-2024 02:08:42 Running post build plugin 'NCover Results Collector'
11-Sep-2024 02:08:42 Running post build plugin 'Build Results Label Collector'
11-Sep-2024 02:08:42 Running post build plugin 'Clover Results Collector'
11-Sep-2024 02:08:42 Running post build plugin 'npm Cache Cleanup'
11-Sep-2024 02:08:42 Running post build plugin 'Artifact Copier'
11-Sep-2024 02:08:42 Publishing an artifact: Regression images
11-Sep-2024 02:08:42 Finished publishing of artifact Non required shared artifact Http Compression On : [Regression images], patterns: [png_list/*.png] anchored at: [dockershare] in 105.1 ms
11-Sep-2024 02:08:42 Finalising the build...
11-Sep-2024 02:08:42 Stopping timer.
11-Sep-2024 02:08:42 Build CASA-CMTC-CRR85D-107 completed.
10-Sep-2024 22:08:42 Running on server: post build plugin 'Build Hanging Detection Configuration'
10-Sep-2024 22:08:42 Running on server: post build plugin 'NCover Results Collector'
10-Sep-2024 22:08:42 Running on server: post build plugin 'Build Labeller'
10-Sep-2024 22:08:42 Running on server: post build plugin 'Clover Delta Calculator'
10-Sep-2024 22:08:42 Running on server: post build plugin 'Maven Dependencies Postprocessor'
10-Sep-2024 22:08:43 All post build plugins have finished
10-Sep-2024 22:08:43 Generating build results summary...
10-Sep-2024 22:08:43 Saving build results to disk...
10-Sep-2024 22:08:43 Store variable context...
10-Sep-2024 22:08:43 Finished building CASA-CMTC-CRR85D-107.