Build: #3 did not complete

Job: Test Tools Many Linux 2.28 did not complete

Build log

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

24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 42 [progress: 76%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 12, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 43 [progress: 77%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 13, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 44 [progress: 78%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 14, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 45 [progress: 80%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 15, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 46 [progress: 81%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 0, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 47 [progress: 82%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 1, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 48 [progress: 84%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 2, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 49 [progress: 85%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 3, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 50 [progress: 86%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 4, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 51 [progress: 88%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 5, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 52 [progress: 89%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 6, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 53 [progress: 90%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 7, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 54 [progress: 92%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 9, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 55 [progress: 93%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 10, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 56 [progress: 94%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 11, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 57 [progress: 96%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 12, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 58 [progress: 97%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 13, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 59 [progress: 98%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 14, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 60 [progress: 100%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 15, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (16640/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::nextChunk         ====================================================================================
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Unflag::tableSummary        => Percentage of data un-flagged in table selection: 100%
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        AgentFlagger::run        => Writing flags to the MS
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        AgentFlagger::open        Table type is Calibration
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::selectData         Selection not applicable, using entire MS
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::selectData         Original CalTable has 9750 rows, and selected CalTable has 9750 rows
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        AgentFlagger::initAgents        There are initially 5 agents in the list. Agents: clip, tfcrop, summary, extend, summary
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::setAgentParameters          data column is CPARAM
24-Apr-2023 11:51:06 2023-04-24 15:51:06        WARN        Clip::setAgentParameters (file /source/casa6/casatools/src/code/flagging/Flagging/FlagAgentBase.cc, line 1171)         Unsupported complex operator: SOL1; using ABS by default.  Supported expressions: REAL,IMAG,ARG,ABS,NORM.
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::setAgentParameters          Visibility expression is ABS SOL1
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::setAgentParameters          clipzeros is 1
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::setAgentParameters          clipoutside is 1
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::setAgentParameters          clipminmax range not provided
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::setAgentParameters          Clipping range not provided, clipping NaNs and zeros
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          data column is CPARAM
24-Apr-2023 11:51:06 2023-04-24 15:51:06        WARN        Tfcrop::setAgentParameters (file /source/casa6/casatools/src/code/flagging/Flagging/FlagAgentBase.cc, line 1171)         Unsupported complex operator: SOL1; using ABS by default.  Supported expressions: REAL,IMAG,ARG,ABS,NORM.
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          Visibility expression is ABS SOL1
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          timecutoff is 4
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          freqcutoff is 3
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          maxnpieces is 7
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          timefit is line
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          freqfit is poly
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          flagdimension is freqtime
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          halfwin is 1
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::setAgentParameters          usewindowstats is none
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::setAgentParameters          extendpols is 1
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::setAgentParameters          growtime is 0
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::setAgentParameters          growfreq is 0
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::setAgentParameters          growaround is 0
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::setAgentParameters          flagneartime is 0
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::setAgentParameters          flagnearfreq is 0
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        AgentFlagger::initAgents        There are 5 valid agents in the list. Agents: clip, tfcrop, summary, extend, summary
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 1 [progress: 2%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 0, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.33774% (778/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 2.33774% (778/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 2 [progress: 5%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 1, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (630/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.88101% (2290/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 6.88101% (2290/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 3 [progress: 8%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 2, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (630/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 7.13942% (2376/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 7.13942% (2376/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 4 [progress: 10%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 3, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (630/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.42428% (2138/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 6.42428% (2138/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 5 [progress: 13%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 4, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (630/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 8.04087% (2676/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 8.04087% (2676/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 6 [progress: 16%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 5, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (630/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.14183% (2044/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 6.14183% (2044/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 7 [progress: 18%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 6, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.92007% (639/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 7.03125% (2340/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 7.03125% (2340/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 8 [progress: 21%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 7, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.92308% (640/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.36178% (786/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 6.20793% (2066/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 9 [progress: 24%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 9, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.45192% (816/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 2.45192% (816/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 10 [progress: 26%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 10, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.03726% (678/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 2.03726% (678/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 11 [progress: 29%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 11, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.27163% (756/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 2.27163% (756/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 12 [progress: 32%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 12, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 3.19111% (1062/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 3.19111% (1062/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 13 [progress: 34%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 13, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 3.11298% (1036/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 3.11298% (1036/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 14 [progress: 37%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 14, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 3.50361% (1166/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 3.50361% (1166/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 15 [progress: 40%], Observation = 0~0, Scan = -1~-1, Field = 2 (3C286_A), Spw = 15, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 260
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 4.04447% (1346/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 4.04447% (1346/33280)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 16 [progress: 41%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 0, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.76683% (1126/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 6.76683% (1126/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 17 [progress: 42%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 1, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 4.6875% (780/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 4.6875% (780/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 18 [progress: 44%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 2, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.91707% (319/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 5.22837% (870/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 5.22837% (870/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 19 [progress: 45%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 3, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.91707% (319/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 4.69952% (782/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 4.69952% (782/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 20 [progress: 46%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 4, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.91106% (318/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 10.2404% (1704/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 10.2404% (1704/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 21 [progress: 48%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 5, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.91707% (319/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 9.13462% (1520/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 9.13462% (1520/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 22 [progress: 49%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 6, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.91106% (318/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 5.70913% (950/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 5.70913% (950/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 23 [progress: 50%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 7, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Clip::chunkSummary        => Data flagged so far 1.91106% (318/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 5.02404% (836/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 5.02404% (836/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 24 [progress: 52%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 9, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.09135% (348/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 2.09135% (348/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 25 [progress: 53%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 10, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.46394% (410/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 2.46394% (410/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 26 [progress: 54%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 11, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.17548% (362/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 2.17548% (362/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 27 [progress: 56%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 12, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.35577% (392/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        Extend::chunkSummary        => Data flagged so far 2.35577% (392/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:06        INFO        FlagCalTableHandler::        Chunk = 28 [progress: 57%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 13, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 3.97837% (662/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 3.97837% (662/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 29 [progress: 58%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 14, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.84856% (474/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.84856% (474/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 30 [progress: 60%], Observation = 0~0, Scan = -1~-1, Field = 3 (3C286_C), Spw = 15, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 3.42548% (570/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 3.42548% (570/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 31 [progress: 61%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 0, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.51202% (418/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.51202% (418/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 32 [progress: 62%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 1, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.79087% (1130/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 6.79087% (1130/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 33 [progress: 64%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 2, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 7.16346% (1192/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 7.16346% (1192/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 34 [progress: 65%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 3, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.41827% (1068/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 6.41827% (1068/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 35 [progress: 66%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 4, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 10.3726% (1726/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 10.3726% (1726/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 36 [progress: 68%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 5, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.61058% (1100/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 6.61058% (1100/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 37 [progress: 69%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 6, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.97115% (1160/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 6.97115% (1160/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 38 [progress: 70%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 7, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.92308% (320/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.03125% (338/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 5.8774% (978/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 39 [progress: 72%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 9, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.53606% (422/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.53606% (422/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 40 [progress: 73%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 10, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.16346% (360/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.16346% (360/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 41 [progress: 74%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 11, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.47596% (412/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.47596% (412/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 42 [progress: 76%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 12, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.71635% (452/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.71635% (452/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 43 [progress: 77%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 13, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 3.4375% (572/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 3.4375% (572/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 44 [progress: 78%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 14, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 3.6899% (614/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 3.6899% (614/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 45 [progress: 80%], Observation = 0~0, Scan = -1~-1, Field = 4 (3C286_B), Spw = 15, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 4.07452% (678/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 4.07452% (678/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 46 [progress: 81%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 0, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.42788% (404/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.42788% (404/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 47 [progress: 82%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 1, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.99519% (1164/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 6.99519% (1164/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 48 [progress: 84%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 2, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 7.59615% (1264/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 7.59615% (1264/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 49 [progress: 85%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 3, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 6.6226% (1102/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 6.6226% (1102/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 50 [progress: 86%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 4, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 13.5817% (2260/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 13.5817% (2260/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 51 [progress: 88%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 5, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.89303% (315/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 7.25962% (1208/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 7.25962% (1208/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 52 [progress: 89%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 6, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.91106% (318/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 7.34375% (1222/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 7.34375% (1222/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 53 [progress: 90%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 7, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Clip::chunkSummary        => Data flagged so far 1.92308% (320/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.21154% (368/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 6.05769% (1008/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 54 [progress: 92%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 9, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.19952% (366/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.19952% (366/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 55 [progress: 93%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 10, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.31971% (386/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.31971% (386/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 56 [progress: 94%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 11, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 2.16346% (360/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 2.16346% (360/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 57 [progress: 96%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 12, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 3.10096% (516/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 3.10096% (516/16640)
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:06 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 58 [progress: 97%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 13, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 4.08654% (680/16640)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 4.08654% (680/16640)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 59 [progress: 98%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 14, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 3.59375% (598/16640)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 3.59375% (598/16640)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        ------------------------------------------------------------------------------------
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        FlagCalTableHandler::        Chunk = 60 [progress: 100%], Observation = 0~0, Scan = -1~-1, Field = 5 (3C286_D), Spw = 15, Channels = 64, CalSolutions = [ Sol1 Sol2 ], Total Rows = 130
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Tfcrop::chunkSummary        => Data flagged so far 4.07452% (678/16640)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Extend::chunkSummary        => Data flagged so far 4.07452% (678/16640)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        FlagCalTableHandler::nextChunk         ====================================================================================
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Clip::tableSummary        => Percentage of data flagged in table selection: 0.88766%
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Tfcrop::tableSummary        => Percentage of data flagged in table selection: 4.67083%
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Extend::tableSummary        => Percentage of data flagged in table selection: 4.87596%
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        AgentFlagger::run        => Writing flags to the MS
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          observation 0 flagged: 30426 total: 1.248e+06 (2.44%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          scan -1 flagged: 30426 total: 1.248e+06 (2.44%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea27 flagged: 894 total: 48000 (1.86%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea25 flagged: 900 total: 48000 (1.88%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea24 flagged: 816 total: 48000 (1.7%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea23 flagged: 872 total: 48000 (1.82%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea22 flagged: 826 total: 48000 (1.72%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea18 flagged: 788 total: 48000 (1.64%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea07 flagged: 759 total: 48000 (1.58%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea03 flagged: 751 total: 48000 (1.56%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea01 flagged: 814 total: 48000 (1.7%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea15 flagged: 855 total: 48000 (1.78%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea28 flagged: 11509 total: 48000 (24%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea16 flagged: 813 total: 48000 (1.69%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea02 flagged: 868 total: 48000 (1.81%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea09 flagged: 724 total: 48000 (1.51%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea20 flagged: 274 total: 48000 (0.571%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea17 flagged: 285 total: 48000 (0.594%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea10 flagged: 774 total: 48000 (1.61%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea21 flagged: 847 total: 48000 (1.76%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea19 flagged: 864 total: 48000 (1.8%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea04 flagged: 664 total: 48000 (1.38%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea08 flagged: 841 total: 48000 (1.75%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea11 flagged: 861 total: 48000 (1.79%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea12 flagged: 782 total: 48000 (1.63%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea13 flagged: 810 total: 48000 (1.69%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea05 flagged: 472 total: 48000 (0.983%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea14 flagged: 763 total: 48000 (1.59%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          field 3C286_D flagged: 6608 total: 249600 (2.65%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          field 3C286_B flagged: 6141 total: 249600 (2.46%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          field 3C286_A flagged: 11784 total: 499200 (2.36%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          field 3C286_C flagged: 5893 total: 249600 (2.36%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 15 flagged: 1636 total: 83200 (1.97%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 14 flagged: 1426 total: 83200 (1.71%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 10 flagged: 917 total: 83200 (1.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 7 flagged: 2444 total: 83200 (2.94%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 12 flagged: 1211 total: 83200 (1.46%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 6 flagged: 2836 total: 83200 (3.41%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 5 flagged: 2936 total: 83200 (3.53%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 0 flagged: 1363 total: 83200 (1.64%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 11 flagged: 945 total: 83200 (1.14%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 9 flagged: 976 total: 83200 (1.17%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 4 flagged: 4183 total: 83200 (5.03%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 1 flagged: 2682 total: 83200 (3.22%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 2 flagged: 2851 total: 83200 (3.43%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 13 flagged: 1475 total: 83200 (1.77%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 3 flagged: 2545 total: 83200 (3.06%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          correlation Sol2 flagged: 0 total: 624000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          correlation Sol1 flagged: 30426 total: 624000 (4.88%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          array -1 flagged: 30426 total: 1.248e+06 (2.44%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          Total Flagged: 30426 Total Counts: 1.248e+06 (2.44%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          observation 0 flagged: 60852 total: 1.248e+06 (4.88%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          scan -1 flagged: 60852 total: 1.248e+06 (4.88%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea27 flagged: 1788 total: 48000 (3.73%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea25 flagged: 1800 total: 48000 (3.75%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea24 flagged: 1632 total: 48000 (3.4%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea23 flagged: 1744 total: 48000 (3.63%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea22 flagged: 1652 total: 48000 (3.44%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea18 flagged: 1576 total: 48000 (3.28%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea07 flagged: 1518 total: 48000 (3.16%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea03 flagged: 1502 total: 48000 (3.13%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea01 flagged: 1628 total: 48000 (3.39%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea15 flagged: 1710 total: 48000 (3.56%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea28 flagged: 23018 total: 48000 (48%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea16 flagged: 1626 total: 48000 (3.39%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea02 flagged: 1736 total: 48000 (3.62%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea09 flagged: 1448 total: 48000 (3.02%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea20 flagged: 548 total: 48000 (1.14%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea17 flagged: 570 total: 48000 (1.19%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea10 flagged: 1548 total: 48000 (3.23%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea21 flagged: 1694 total: 48000 (3.53%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea19 flagged: 1728 total: 48000 (3.6%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea04 flagged: 1328 total: 48000 (2.77%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea08 flagged: 1682 total: 48000 (3.5%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea11 flagged: 1722 total: 48000 (3.59%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea12 flagged: 1564 total: 48000 (3.26%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea13 flagged: 1620 total: 48000 (3.38%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea05 flagged: 944 total: 48000 (1.97%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          antenna ea14 flagged: 1526 total: 48000 (3.18%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          field 3C286_D flagged: 13216 total: 249600 (5.29%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          field 3C286_B flagged: 12282 total: 249600 (4.92%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          field 3C286_A flagged: 23568 total: 499200 (4.72%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          field 3C286_C flagged: 11786 total: 249600 (4.72%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 15 flagged: 3272 total: 83200 (3.93%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 14 flagged: 2852 total: 83200 (3.43%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 10 flagged: 1834 total: 83200 (2.2%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 7 flagged: 4888 total: 83200 (5.88%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 12 flagged: 2422 total: 83200 (2.91%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 6 flagged: 5672 total: 83200 (6.82%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 5 flagged: 5872 total: 83200 (7.06%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 0 flagged: 2726 total: 83200 (3.28%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 11 flagged: 1890 total: 83200 (2.27%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 9 flagged: 1952 total: 83200 (2.35%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 4 flagged: 8366 total: 83200 (10.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 1 flagged: 5364 total: 83200 (6.45%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 2 flagged: 5702 total: 83200 (6.85%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 13 flagged: 2950 total: 83200 (3.55%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          spw 3 flagged: 5090 total: 83200 (6.12%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          correlation Sol2 flagged: 30426 total: 624000 (4.88%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          correlation Sol1 flagged: 30426 total: 624000 (4.88%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          array -1 flagged: 60852 total: 1.248e+06 (4.88%)
24-Apr-2023 11:51:07 2023-04-24 15:51:07        INFO        Summary::getResult          Total Flagged: 60852 Total Counts: 1.248e+06 (4.88%)
24-Apr-2023 11:51:07 test_tool_agentflagger.py::test_bpass::test_tfcrop_cparam_sol1_extension
24-Apr-2023 11:51:07 AgentFlagger:: Test tfcrop first calibration solution product of CPARAM column,
24-Apr-2023 11:51:07 and then extend to the other solution for bpass CalTable
24-Apr-2023 11:51:07 The CalTable is already around, just unflag
24-Apr-2023 11:51:07 PASSED
24-Apr-2023 11:51:07
24-Apr-2023 11:51:07
Request to stop 'CASA-CMDTF1-TTML228-3' received from vsuorant
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::open        Table type is Measurement Set
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: unflag
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: unflag
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 1 [progress: 5%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J2253+161; 3c454.3), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 104
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 2 [progress: 47%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J2253+161; 3c454.3), Spw = 1, Channels = 128, Corrs = [ XX YY ], Total Rows = 750
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (192416/192416)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 3 [progress: 48%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 24
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (96/96)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 4 [progress: 57%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 2, Channels = 128, Corrs = [ XX YY ], Total Rows = 150
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (38400/38400)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 5 [progress: 73%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 3, Channels = 1, Corrs = [ XX YY ], Total Rows = 300
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (600/600)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 6 [progress: 75%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 24
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (96/96)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 7 [progress: 83%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 2, Channels = 128, Corrs = [ XX YY ], Total Rows = 150
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (38400/38400)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 8 [progress: 100%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 3, Channels = 1, Corrs = [ XX YY ], Total Rows = 300
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (600/600)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::nextChunk         ====================================================================================
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::tableSummary        => Percentage of data un-flagged in table selection: 100%
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::run        => Writing flags to the MS
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::open        Table type is Measurement Set
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::initAgents        There are initially 5 agents in the list. Agents: manual, manual, manual, manual, summary
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::setAgentParameters         autocorr is 0
24-Apr-2023 11:51:07 2023-04-24 15:51:08        WARN        AgentFlagger::initAgents        Cannot create agent Manual: State Expression: No match found for "FOCUS"
24-Apr-2023 11:51:07 2023-04-24 15:51:08        WARN        AgentFlagger::initAgents+        State Expression: Partial or no match for State ID list []
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::setAgentParameters         autocorr is 0
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::setAgentParameters         autocorr is 0
24-Apr-2023 11:51:07 2023-04-24 15:51:08        WARN        AgentFlagger::initAgents        Cannot create agent Manual: State Expression: No match found for "CALIBRATE_AMPLI_ON_SOURC"
24-Apr-2023 11:51:07 2023-04-24 15:51:08        WARN        AgentFlagger::initAgents+        State Expression: Partial or no match for State ID list []
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::setAgentParameters         autocorr is 0
24-Apr-2023 11:51:07 2023-04-24 15:51:08        WARN        AgentFlagger::initAgents        Cannot create agent Manual: State Expression: No match found for "*DELAY*"
24-Apr-2023 11:51:07 2023-04-24 15:51:08        WARN        AgentFlagger::initAgents+        State Expression: Partial or no match for State ID list []
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::initAgents        There are 2 valid agents in the list. Agents: manual, summary
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 1 [progress: 5%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J2253+161; 3c454.3), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 104
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 2 [progress: 47%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J2253+161; 3c454.3), Spw = 1, Channels = 128, Corrs = [ XX YY ], Total Rows = 750
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::chunkSummary        => Data flagged so far 100% (192416/192416)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 3 [progress: 48%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 24
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 4 [progress: 57%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 2, Channels = 128, Corrs = [ XX YY ], Total Rows = 150
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 5 [progress: 73%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 3, Channels = 1, Corrs = [ XX YY ], Total Rows = 300
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 6 [progress: 75%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 24
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 7 [progress: 83%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 2, Channels = 128, Corrs = [ XX YY ], Total Rows = 150
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 8 [progress: 100%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 3, Channels = 1, Corrs = [ XX YY ], Total Rows = 300
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::nextChunk         ====================================================================================
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 71.1051%
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::run        => Writing flags to the MS
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          observation 0 flagged: 192416 total: 270608 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 10 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 1 flagged: 192416 total: 192416 (100%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 8 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV14 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV13 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV12 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV07 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV05 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV10 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA42 flagged: 53864 total: 75752 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA43 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna PM02 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV11 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA41 flagged: 53864 total: 75752 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV03 flagged: 15360 total: 21600 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV02 flagged: 53864 total: 75752 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna PM03 flagged: 53864 total: 75752 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field GRB021004 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field J2253+161; 3c454.3 flagged: 192416 total: 192416 (100%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field B0007+106; J0010+109 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 3 flagged: 0 total: 1200 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 2 flagged: 0 total: 76800 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 0 flagged: 416 total: 608 (68.4%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 1 flagged: 192000 total: 192000 (100%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation YY flagged: 96000 total: 135000 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation I flagged: 416 total: 608 (68.4%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation XX flagged: 96000 total: 135000 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          array 0 flagged: 192416 total: 270608 (71.1%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          Total Flagged: 192416 Total Counts: 270608 (71.1%)
24-Apr-2023 11:51:07 test_tool_agentflagger.py::test_MS::test_null_intent_selection1
24-Apr-2023 11:51:07 Agentflagger: handle unknown scan intent in list mode
24-Apr-2023 11:51:07 Moving data...
24-Apr-2023 11:51:07 PASSED
24-Apr-2023 11:51:07
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::open        Table type is Measurement Set
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: unflag
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: unflag
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 1 [progress: 5%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J2253+161; 3c454.3), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 104
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 2 [progress: 47%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J2253+161; 3c454.3), Spw = 1, Channels = 128, Corrs = [ XX YY ], Total Rows = 750
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (192416/192416)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 3 [progress: 48%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 24
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (96/96)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 4 [progress: 57%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 2, Channels = 128, Corrs = [ XX YY ], Total Rows = 150
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (38400/38400)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 5 [progress: 73%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 3, Channels = 1, Corrs = [ XX YY ], Total Rows = 300
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (600/600)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 6 [progress: 75%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 24
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (96/96)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 7 [progress: 83%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 2, Channels = 128, Corrs = [ XX YY ], Total Rows = 150
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (38400/38400)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 8 [progress: 100%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 3, Channels = 1, Corrs = [ XX YY ], Total Rows = 300
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (600/600)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::nextChunk         ====================================================================================
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Unflag::tableSummary        => Percentage of data un-flagged in table selection: 100%
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::run        => Writing flags to the MS
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::open        Table type is Measurement Set
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::initAgents        There are initially 5 agents in the list. Agents: summary, manual, summary, manual, summary
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::setAgentParameters         autocorr is 0
24-Apr-2023 11:51:07 2023-04-24 15:51:08        WARN        AgentFlagger::initAgents        Cannot create agent Manual: State Expression: No match found for "FOCUS"
24-Apr-2023 11:51:07 2023-04-24 15:51:08        WARN        AgentFlagger::initAgents+        State Expression: Partial or no match for State ID list []
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::setAgentParameters         autocorr is 0
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::initAgents        There are 4 valid agents in the list. Agents: summary, summary, manual, summary
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 1 [progress: 5%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J2253+161; 3c454.3), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 104
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 2 [progress: 47%], Observation = 0, Array = 0, Scan = 1, Field = 0 (J2253+161; 3c454.3), Spw = 1, Channels = 128, Corrs = [ XX YY ], Total Rows = 750
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::chunkSummary        => Data flagged so far 0.216198% (416/192416)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 3 [progress: 48%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 24
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::chunkSummary        => Data flagged so far 100% (96/96)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 4 [progress: 57%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 2, Channels = 128, Corrs = [ XX YY ], Total Rows = 150
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 5 [progress: 73%], Observation = 0, Array = 0, Scan = 8, Field = 2 (B0007+106; J0010+109), Spw = 3, Channels = 1, Corrs = [ XX YY ], Total Rows = 300
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 6 [progress: 75%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 0, Channels = 4, Corrs = [ I ], Total Rows = 24
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::chunkSummary        => Data flagged so far 100% (96/96)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 7 [progress: 83%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 2, Channels = 128, Corrs = [ XX YY ], Total Rows = 150
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::        Chunk = 8 [progress: 100%], Observation = 0, Array = 0, Scan = 10, Field = 3 (GRB021004), Spw = 3, Channels = 1, Corrs = [ XX YY ], Total Rows = 300
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        FlagMSHandler::nextChunk         ====================================================================================
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 0.224679%
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        AgentFlagger::run        => Writing flags to the MS
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          observation 0 flagged: 0 total: 270608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 10 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 1 flagged: 0 total: 192416 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 8 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV14 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV13 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV12 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV07 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV05 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV10 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA42 flagged: 0 total: 75752 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA43 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna PM02 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV11 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA41 flagged: 0 total: 75752 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV03 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV02 flagged: 0 total: 75752 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna PM03 flagged: 0 total: 75752 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field GRB021004 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field J2253+161; 3c454.3 flagged: 0 total: 192416 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field B0007+106; J0010+109 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 3 flagged: 0 total: 1200 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 2 flagged: 0 total: 76800 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 0 flagged: 0 total: 608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 1 flagged: 0 total: 192000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation YY flagged: 0 total: 135000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation I flagged: 0 total: 608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation XX flagged: 0 total: 135000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          array 0 flagged: 0 total: 270608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          Total Flagged: 0 Total Counts: 270608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          observation 0 flagged: 0 total: 270608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 10 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 1 flagged: 0 total: 192416 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 8 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV14 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV13 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV12 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV07 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV05 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV10 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA42 flagged: 0 total: 75752 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA43 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna PM02 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV11 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA41 flagged: 0 total: 75752 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV03 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV02 flagged: 0 total: 75752 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna PM03 flagged: 0 total: 75752 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field GRB021004 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field J2253+161; 3c454.3 flagged: 0 total: 192416 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field B0007+106; J0010+109 flagged: 0 total: 39096 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 3 flagged: 0 total: 1200 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 2 flagged: 0 total: 76800 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 0 flagged: 0 total: 608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 1 flagged: 0 total: 192000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation YY flagged: 0 total: 135000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation I flagged: 0 total: 608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation XX flagged: 0 total: 135000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          array 0 flagged: 0 total: 270608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          Total Flagged: 0 Total Counts: 270608 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          observation 0 flagged: 608 total: 270608 (0.225%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 10 flagged: 96 total: 39096 (0.246%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 1 flagged: 416 total: 192416 (0.216%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          scan 8 flagged: 96 total: 39096 (0.246%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV14 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV13 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV12 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV07 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV05 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV10 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA42 flagged: 152 total: 75752 (0.201%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA43 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna PM02 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV11 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DA41 flagged: 152 total: 75752 (0.201%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV03 flagged: 0 total: 21600 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna DV02 flagged: 152 total: 75752 (0.201%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          antenna PM03 flagged: 152 total: 75752 (0.201%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field GRB021004 flagged: 96 total: 39096 (0.246%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field J2253+161; 3c454.3 flagged: 416 total: 192416 (0.216%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          field B0007+106; J0010+109 flagged: 96 total: 39096 (0.246%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 3 flagged: 0 total: 1200 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 2 flagged: 0 total: 76800 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 0 flagged: 608 total: 608 (100%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          spw 1 flagged: 0 total: 192000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation YY flagged: 0 total: 135000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation I flagged: 608 total: 608 (100%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          correlation XX flagged: 0 total: 135000 (0%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          array 0 flagged: 608 total: 270608 (0.225%)
24-Apr-2023 11:51:07 2023-04-24 15:51:08        INFO        Summary::getResult          Total Flagged: 608 Total Counts: 270608 (0.225%)
24-Apr-2023 11:51:07 test_tool_agentflagger.py::test_MS::test_summarylist1
24-Apr-2023 11:51:07 agentflagger: multiple summaries
24-Apr-2023 11:51:07 The MS is already around, just unflag
24-Apr-2023 11:51:08 PASSED
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 Failing task since return code of [/export/home/cbt-el7-6/bamboohome/temp/CASA-CMDTF1-TTML228-3-ScriptBuildTask-5109231338936623585.sh] was 143 while expected 0
24-Apr-2023 11:51:08 Finished task 'Test wheel' with result: Failed
24-Apr-2023 11:51:08 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
24-Apr-2023 11:51:08
Beginning to execute external process for build 'CASA - CMake Dev Test Full - CAS-9094 - Test Tools Many Linux 2.28 #3 (CASA-CMDTF1-TTML228-3)'
... running command line:
/export/home/cbt-el7-6/bamboohome/temp/CASA-CMDTF1-TTML228-3-ScriptBuildTask-5842540328676729463.sh
... in: /export/home/cbt-el7-6/bamboohome/xml-data/build-dir/CASA-CMDTF1-TTML228
24-Apr-2023 11:51:08 Starting cleanup
24-Apr-2023 11:51:08 test_tool_agentflagger.py::test_MS_datacols::test_model_no_model_col
24-Apr-2023 11:51:08 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
24-Apr-2023 11:51:08 Attempting to generate stack trace and terminate spawned sub-processes of process id: 14369
24-Apr-2023 11:51:08 Found related process: pid: 8518 ppid: 14369 pgid: 7610 %cpu: 0.0 %mem: 0.0 cmd: /bin/sh
24-Apr-2023 11:51:08 Found related process: pid: 8519 ppid: 8518 pgid: 7610 %cpu: 0.0 %mem: 0.0 cmd: docker
24-Apr-2023 11:51:08 getStackTraceAndKillRelatedProcesses for 2 processes
24-Apr-2023 11:51:08 Executing kill -3 8519
24-Apr-2023 11:51:08 Executing kill -3 8518
24-Apr-2023 11:51:08 SIGQUIT: quit
24-Apr-2023 11:51:08 PC=0x562fed6c7c41 m=0 sigcode=0
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 0 [idle]:
24-Apr-2023 11:51:08 runtime.futex()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/sys_linux_amd64.s:559 +0x21 fp=0x7ffc68d32750 sp=0x7ffc68d32748 pc=0x562fed6c7c41
24-Apr-2023 11:51:08 runtime.futexsleep(0x0?, 0x0?, 0x7ffc68d327f8?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/os_linux.go:69 +0x36 fp=0x7ffc68d327a0 sp=0x7ffc68d32750 pc=0x562fed690cb6
24-Apr-2023 11:51:08 runtime.notesleep(0x562feeecb5c8)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/lock_futex.go:160 +0x87 fp=0x7ffc68d327d8 sp=0x7ffc68d327a0 pc=0x562fed667b27
24-Apr-2023 11:51:08 runtime.mPark(...)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:1457
24-Apr-2023 11:51:08 runtime.stopm()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:2247 +0x8c fp=0x7ffc68d32808 sp=0x7ffc68d327d8 pc=0x562fed69b50c
24-Apr-2023 11:51:08 runtime.findRunnable()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:2874 +0x9e8 fp=0x7ffc68d328f8 sp=0x7ffc68d32808 pc=0x562fed69cba8
24-Apr-2023 11:51:08 runtime.schedule()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:3214 +0xbe fp=0x7ffc68d32930 sp=0x7ffc68d328f8 pc=0x562fed69d9de
24-Apr-2023 11:51:08 runtime.park_m(0xc000103380?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:3363 +0x14d fp=0x7ffc68d32960 sp=0x7ffc68d32930 pc=0x562fed69df0d
24-Apr-2023 11:51:08 runtime.mcall()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:448 +0x45 fp=0x7ffc68d32970 sp=0x7ffc68d32960 pc=0x562fed6c3a85
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 1 [chan receive]:
24-Apr-2023 11:51:08 runtime.gopark(0x562fed69f9b1?, 0xc00059fa60?, 0xe0?, 0xf9?, 0xc00041eff0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc00059fa50 sp=0xc00059fa30 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.chanrecv(0xc00043a3c0, 0xc00059fbe0, 0x1)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/chan.go:583 +0x49b fp=0xc00059fae0 sp=0xc00059fa50 pc=0x562fed6628db
24-Apr-2023 11:51:08 runtime.chanrecv1(0x562fee721a78?, 0xc000050118?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/chan.go:442 +0x18 fp=0xc00059fb08 sp=0xc00059fae0 pc=0x562fed6623d8
24-Apr-2023 11:51:08 github.com/docker/cli/cli/command/container.runStop({0x562fee72ad18, 0xc000418500}, 0xc00042fc40)
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cli/command/container/stop.go:63 +0x265 fp=0xc00059fc00 sp=0xc00059fb08 pc=0x562fedd6e225
24-Apr-2023 11:51:08 github.com/docker/cli/cli/command/container.NewStopCommand.func1(0xc0001ebb00?, {0xc00011f090?, 0x1?, 0x1?})
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cli/command/container/stop.go:35 +0x85 fp=0xc00059fc40 sp=0xc00059fc00 pc=0x562fedd6df65
24-Apr-2023 11:51:08 github.com/docker/cli/vendor/github.com/spf13/cobra.(*Command).execute(0xc0001ebb00, {0xc0002dbe50, 0x1, 0x1})
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/vendor/github.com/spf13/cobra/command.go:916 +0x862 fp=0xc00059fd78 sp=0xc00059fc40 pc=0x562fedc4b8c2
24-Apr-2023 11:51:08 github.com/docker/cli/vendor/github.com/spf13/cobra.(*Command).ExecuteC(0xc000554000)
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/vendor/github.com/spf13/cobra/command.go:1044 +0x3bd fp=0xc00059fe30 sp=0xc00059fd78 pc=0x562fedc4c13d
24-Apr-2023 11:51:08 github.com/docker/cli/vendor/github.com/spf13/cobra.(*Command).Execute(...)
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/vendor/github.com/spf13/cobra/command.go:968
24-Apr-2023 11:51:08 main.runDocker(0x0?)
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cmd/docker/docker.go:263 +0x3e5 fp=0xc00059ff08 sp=0xc00059fe30 pc=0x562fee0e1b25
24-Apr-2023 11:51:08 main.main()
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cmd/docker/docker.go:274 +0x97 fp=0xc00059ff80 sp=0xc00059ff08 pc=0x562fee0e1d17
24-Apr-2023 11:51:08 runtime.main()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:250 +0x213 fp=0xc00059ffe0 sp=0xc00059ff80 pc=0x562fed697333
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc00059ffe8 sp=0xc00059ffe0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 2 [force gc (idle)]:
24-Apr-2023 11:51:08 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000072fb0 sp=0xc000072f90 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.goparkunlock(...)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:369
24-Apr-2023 11:51:08 runtime.forcegchelper()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:302 +0xad fp=0xc000072fe0 sp=0xc000072fb0 pc=0x562fed69758d
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc000072fe8 sp=0xc000072fe0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by runtime.init.6
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:290 +0x25
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 3 [GC sweep wait]:
24-Apr-2023 11:51:08 runtime.gopark(0x1?, 0x0?, 0x0?, 0x0?, 0x0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000073790 sp=0xc000073770 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.goparkunlock(...)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:369
24-Apr-2023 11:51:08 runtime.bgsweep(0x0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgcsweep.go:297 +0xd7 fp=0xc0000737c8 sp=0xc000073790 pc=0x562fed682c37
24-Apr-2023 11:51:08 runtime.gcenable.func1()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:178 +0x26 fp=0xc0000737e0 sp=0xc0000737c8 pc=0x562fed677886
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc0000737e8 sp=0xc0000737e0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by runtime.gcenable
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:178 +0x6b
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 4 [GC scavenge wait]:
24-Apr-2023 11:51:08 runtime.gopark(0xc00004e460?, 0x562fee2bbe98?, 0x0?, 0x0?, 0x0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000073f70 sp=0xc000073f50 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.goparkunlock(...)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:369
24-Apr-2023 11:51:08 runtime.(*scavengerState).park(0x562feeecab40)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgcscavenge.go:389 +0x53 fp=0xc000073fa0 sp=0xc000073f70 pc=0x562fed680c93
24-Apr-2023 11:51:08 runtime.bgscavenge(0x0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgcscavenge.go:622 +0x65 fp=0xc000073fc8 sp=0xc000073fa0 pc=0x562fed681285
24-Apr-2023 11:51:08 runtime.gcenable.func2()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:179 +0x26 fp=0xc000073fe0 sp=0xc000073fc8 pc=0x562fed677826
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc000073fe8 sp=0xc000073fe0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by runtime.gcenable
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:179 +0xaa
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 5 [finalizer wait]:
24-Apr-2023 11:51:08 runtime.gopark(0x562feeecb480?, 0xc000007860?, 0x0?, 0x0?, 0xc000072770?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000072628 sp=0xc000072608 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.goparkunlock(...)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:369
24-Apr-2023 11:51:08 runtime.runfinq()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mfinal.go:180 +0x10f fp=0xc0000727e0 sp=0xc000072628 pc=0x562fed67698f
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc0000727e8 sp=0xc0000727e0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by runtime.createfing
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mfinal.go:157 +0x45
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 34 [GC worker (idle)]:
24-Apr-2023 11:51:08 runtime.gopark(0xc000074780?, 0x562fed66167d?, 0x15?, 0x0?, 0xc0000747a8?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000074750 sp=0xc000074730 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.gcBgMarkWorker()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:1235 +0xf1 fp=0xc0000747e0 sp=0xc000074750 pc=0x562fed6799f1
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc0000747e8 sp=0xc0000747e0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by runtime.gcBgMarkStartWorkers
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:1159 +0x25
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 7 [GC worker (idle)]:
24-Apr-2023 11:51:08 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000074f50 sp=0xc000074f30 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.gcBgMarkWorker()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:1235 +0xf1 fp=0xc000074fe0 sp=0xc000074f50 pc=0x562fed6799f1
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc000074fe8 sp=0xc000074fe0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by runtime.gcBgMarkStartWorkers
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:1159 +0x25
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 35 [GC worker (idle)]:
24-Apr-2023 11:51:08 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc00006e750 sp=0xc00006e730 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.gcBgMarkWorker()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:1235 +0xf1 fp=0xc00006e7e0 sp=0xc00006e750 pc=0x562fed6799f1
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc00006e7e8 sp=0xc00006e7e0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by runtime.gcBgMarkStartWorkers
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:1159 +0x25
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 8 [GC worker (idle)]:
24-Apr-2023 11:51:08 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000075750 sp=0xc000075730 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.gcBgMarkWorker()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:1235 +0xf1 fp=0xc0000757e0 sp=0xc000075750 pc=0x562fed6799f1
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc0000757e8 sp=0xc0000757e0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by runtime.gcBgMarkStartWorkers
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/mgc.go:1159 +0x25
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 36 [chan receive]:
24-Apr-2023 11:51:08 runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc00006eec0 sp=0xc00006eea0 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.chanrecv(0xc00009d860, 0xc00006efc0, 0x1)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/chan.go:583 +0x49b fp=0xc00006ef50 sp=0xc00006eec0 pc=0x562fed6628db
24-Apr-2023 11:51:08 runtime.chanrecv1(0x562fee5b2c80?, 0xc000200660?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/chan.go:442 +0x18 fp=0xc00006ef78 sp=0xc00006ef50 pc=0x562fed6623d8
24-Apr-2023 11:51:08 github.com/docker/cli/cli/command/container.parallelOperation.func1()
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cli/command/container/utils.go:147 +0x92 fp=0xc00006efe0 sp=0xc00006ef78 pc=0x562fedd723d2
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc00006efe8 sp=0xc00006efe0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by github.com/docker/cli/cli/command/container.parallelOperation
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cli/command/container/utils.go:145 +0x1d8
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 38 [select]:
24-Apr-2023 11:51:08 runtime.gopark(0xc0002152a8?, 0x6?, 0x68?, 0x4f?, 0xc0002150bc?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000214f20 sp=0xc000214f00 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.selectgo(0xc0002152a8, 0xc0002150b0, 0xc00011f240?, 0x0, 0xc000215098?, 0x1)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/select.go:328 +0x7bc fp=0xc000215060 sp=0xc000214f20 pc=0x562fed6a6b7c
24-Apr-2023 11:51:08 net/http.(*persistConn).roundTrip(0xc0001ed680, 0xc000330140)
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/transport.go:2620 +0x974 fp=0xc000215318 sp=0xc000215060 pc=0x562fed95c034
24-Apr-2023 11:51:08 net/http.(*Transport).roundTrip(0xc00039e780, 0xc0001f6e00)
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/transport.go:595 +0x7ba fp=0xc000215540 sp=0xc000215318 pc=0x562fed94fb9a
24-Apr-2023 11:51:08 net/http.(*Transport).RoundTrip(0x203000?, 0x562fee7161e0?)
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/roundtrip.go:17 +0x19 fp=0xc000215560 sp=0xc000215540 pc=0x562fed943cf9
24-Apr-2023 11:51:08 net/http.send(0xc0001f6e00, {0x562fee7161e0, 0xc00039e780}, {0x562fee6ef800?, 0x562fee0e7601?, 0x0?})
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/client.go:251 +0x5f7 fp=0xc000215758 sp=0xc000215560 pc=0x562fed919b37
24-Apr-2023 11:51:08 net/http.(*Client).send(0xc000200750, 0xc0001f6e00, {0xc000215858?, 0x562fed66931f?, 0x0?})
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/client.go:175 +0x9b fp=0xc0002157d0 sp=0xc000215758 pc=0x562fed9193bb
24-Apr-2023 11:51:08 net/http.(*Client).do(0xc000200750, 0xc0001f6e00)
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/client.go:715 +0x8fc fp=0xc0002159c0 sp=0xc0002157d0 pc=0x562fed91b73c
24-Apr-2023 11:51:08 net/http.(*Client).Do(...)
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/client.go:581
24-Apr-2023 11:51:08 github.com/docker/cli/vendor/github.com/docker/docker/client.(*Client).doRequest(0xc00042b000, {0x562fee721a78, 0xc000050118}, 0xc0001f6d00)
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/vendor/github.com/docker/docker/client/request.go:137 +0x16a fp=0xc000215ab0 sp=0xc0002159c0 pc=0x562feda3fbaa
24-Apr-2023 11:51:08 github.com/docker/cli/vendor/github.com/docker/docker/client.(*Client).sendRequest(0x562fed6af83c?, {0x562fee721a78, 0xc000050118}, {0x562fee0e7856, 0x4}, {0xc000448db0?, 0x5?}, 0x5?, {0x0, 0x0}, ...)
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/vendor/github.com/docker/docker/client/request.go:121 +0xd3 fp=0xc000215b98 sp=0xc000215ab0 pc=0x562feda3f773
24-Apr-2023 11:51:08 github.com/docker/cli/vendor/github.com/docker/docker/client.(*Client).post(0x1?, {0x562fee721a78, 0xc000050118}, {0xc000448db0, 0x25}, 0x562fee0e8037?, {0x0?, 0x0?}, 0x1?)
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/vendor/github.com/docker/docker/client/request.go:45 +0x118 fp=0xc000215c88 sp=0xc000215b98 pc=0x562feda3ee98
24-Apr-2023 11:51:08 github.com/docker/cli/vendor/github.com/docker/docker/client.(*Client).ContainerStop(0xc00042b000, {0x562fee721a78, 0xc000050118}, {0x7ffc68d338d5, 0x14}, {{0x0?, 0x0?}, 0x0?})
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/vendor/github.com/docker/docker/client/container_stop.go:27 +0x2dc fp=0xc000215f10 sp=0xc000215c88 pc=0x562feda296dc
24-Apr-2023 11:51:08 github.com/docker/cli/cli/command/container.runStop.func1({0x562fee721a78, 0xc000050118}, {0x7ffc68d338d5, 0x14})
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cli/command/container/stop.go:56 +0x83 fp=0xc000215f70 sp=0xc000215f10 pc=0x562fedd6e443
24-Apr-2023 11:51:08 github.com/docker/cli/cli/command/container.parallelOperation.func2.1({0x7ffc68d338d5, 0x14})
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cli/command/container/utils.go:156 +0x55 fp=0xc000215fc0 sp=0xc000215f70 pc=0x562fedd722d5
24-Apr-2023 11:51:08 github.com/docker/cli/cli/command/container.parallelOperation.func2.2()
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cli/command/container/utils.go:158 +0x2e fp=0xc000215fe0 sp=0xc000215fc0 pc=0x562fedd7224e
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc000215fe8 sp=0xc000215fe0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by github.com/docker/cli/cli/command/container.parallelOperation.func2
24-Apr-2023 11:51:08         /go/src/github.com/docker/cli/cli/command/container/utils.go:155 +0x19b
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 40 [IO wait]:
24-Apr-2023 11:51:08 runtime.gopark(0x562fee6692e0?, 0xb?, 0x0?, 0x0?, 0x3?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000083af8 sp=0xc000083ad8 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.netpollblock(0x562fed6d9d45?, 0xed6e7a97?, 0x2f?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/netpoll.go:526 +0xf7 fp=0xc000083b30 sp=0xc000083af8 pc=0x562fed690077
24-Apr-2023 11:51:08 internal/poll.runtime_pollWait(0x7fdf8ef2b348, 0x72)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/netpoll.go:305 +0x89 fp=0xc000083b50 sp=0xc000083b30 pc=0x562fed6c0569
24-Apr-2023 11:51:08 internal/poll.(*pollDesc).wait(0xc00042b080?, 0xc00021c000?, 0x0)
24-Apr-2023 11:51:08         /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x32 fp=0xc000083b78 sp=0xc000083b50 pc=0x562fed6fe452
24-Apr-2023 11:51:08 internal/poll.(*pollDesc).waitRead(...)
24-Apr-2023 11:51:08         /usr/local/go/src/internal/poll/fd_poll_runtime.go:89
24-Apr-2023 11:51:08 internal/poll.(*FD).Read(0xc00042b080, {0xc00021c000, 0x1000, 0x1000})
24-Apr-2023 11:51:08         /usr/local/go/src/internal/poll/fd_unix.go:167 +0x25a fp=0xc000083bf8 sp=0xc000083b78 pc=0x562fed6ff7ba
24-Apr-2023 11:51:08 net.(*netFD).Read(0xc00042b080, {0xc00021c000?, 0x0?, 0x4?})
24-Apr-2023 11:51:08         /usr/local/go/src/net/fd_posix.go:55 +0x29 fp=0xc000083c40 sp=0xc000083bf8 pc=0x562fed86a929
24-Apr-2023 11:51:08 net.(*conn).Read(0xc00040ca80, {0xc00021c000?, 0xc00040ca78?, 0x1?})
24-Apr-2023 11:51:08         /usr/local/go/src/net/net.go:183 +0x45 fp=0xc000083c88 sp=0xc000083c40 pc=0x562fed87c445
24-Apr-2023 11:51:08 net/http.(*persistConn).Read(0xc0001ed680, {0xc00021c000?, 0x562fed6a7900?, 0xc000083ec8?})
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/transport.go:1929 +0x4e fp=0xc000083ce8 sp=0xc000083c88 pc=0x562fed9586ce
24-Apr-2023 11:51:08 bufio.(*Reader).fill(0xc00009dbc0)
24-Apr-2023 11:51:08         /usr/local/go/src/bufio/bufio.go:106 +0xff fp=0xc000083d20 sp=0xc000083ce8 pc=0x562fed784e5f
24-Apr-2023 11:51:08 bufio.(*Reader).Peek(0xc00009dbc0, 0x1)
24-Apr-2023 11:51:08         /usr/local/go/src/bufio/bufio.go:144 +0x5d fp=0xc000083d40 sp=0xc000083d20 pc=0x562fed784fbd
24-Apr-2023 11:51:08 net/http.(*persistConn).readLoop(0xc0001ed680)
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/transport.go:2093 +0x1ac fp=0xc000083fc8 sp=0xc000083d40 pc=0x562fed9594ec
24-Apr-2023 11:51:08 net/http.(*Transport).dialConn.func5()
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/transport.go:1751 +0x26 fp=0xc000083fe0 sp=0xc000083fc8 pc=0x562fed957cc6
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc000083fe8 sp=0xc000083fe0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by net/http.(*Transport).dialConn
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/transport.go:1751 +0x173e
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 goroutine 41 [select]:
24-Apr-2023 11:51:08 runtime.gopark(0xc000085f90?, 0x2?, 0xd8?, 0x5d?, 0xc000085f24?)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/proc.go:363 +0xd6 fp=0xc000085d90 sp=0xc000085d70 pc=0x562fed6976f6
24-Apr-2023 11:51:08 runtime.selectgo(0xc000085f90, 0xc000085f20, 0xc000330100?, 0x0, 0x0?, 0x1)
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/select.go:328 +0x7bc fp=0xc000085ed0 sp=0xc000085d90 pc=0x562fed6a6b7c
24-Apr-2023 11:51:08 net/http.(*persistConn).writeLoop(0xc0001ed680)
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/transport.go:2392 +0xf5 fp=0xc000085fc8 sp=0xc000085ed0 pc=0x562fed95b175
24-Apr-2023 11:51:08 net/http.(*Transport).dialConn.func6()
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/transport.go:1752 +0x26 fp=0xc000085fe0 sp=0xc000085fc8 pc=0x562fed957c66
24-Apr-2023 11:51:08 runtime.goexit()
24-Apr-2023 11:51:08         /usr/local/go/src/runtime/asm_amd64.s:1594 +0x1 fp=0xc000085fe8 sp=0xc000085fe0 pc=0x562fed6c5d41
24-Apr-2023 11:51:08 created by net/http.(*Transport).dialConn
24-Apr-2023 11:51:08         /usr/local/go/src/net/http/transport.go:1752 +0x1791
24-Apr-2023 11:51:08
24-Apr-2023 11:51:08 rax    0xca
24-Apr-2023 11:51:08 rbx    0x0
24-Apr-2023 11:51:08 rcx    0xffffffffffffffff
24-Apr-2023 11:51:08 rdx    0x0
24-Apr-2023 11:51:08 rdi    0x562feeecb5c8
24-Apr-2023 11:51:08 rsi    0x80
24-Apr-2023 11:51:08 rbp    0x7ffc68d32790
24-Apr-2023 11:51:08 rsp    0x7ffc68d32748
24-Apr-2023 11:51:08 r8     0x0
24-Apr-2023 11:51:08 r9     0x0
24-Apr-2023 11:51:08 r10    0x0
24-Apr-2023 11:51:08 r11    0x286
24-Apr-2023 11:51:08 r12    0x7ffc68d327a0
24-Apr-2023 11:51:08 r13    0x5
24-Apr-2023 11:51:08 r14    0x562feeecaee0
24-Apr-2023 11:51:08 r15    0x1
24-Apr-2023 11:51:08 rip    0x562fed6c7c41
24-Apr-2023 11:51:08 rflags 0x286
24-Apr-2023 11:51:08 cs     0x33
24-Apr-2023 11:51:08 fs     0x0
24-Apr-2023 11:51:08 gs     0x0
24-Apr-2023 11:51:08 Error response from daemon: You cannot remove a running container ab32d84fb523247d20259695cd9f8c92a984ff66b054c23139d8fa24e5173dc5. Stop the container before attempting removal or force remove
24-Apr-2023 11:51:08 Cleanup complete
24-Apr-2023 11:51:08 Finished task 'Cleanup' with result: Success
24-Apr-2023 11:51:08 Running post build plugin 'Docker Container Cleanup'
24-Apr-2023 11:51:08
Beginning to execute external process for build 'CASA - CMake Dev Test Full - CAS-9094 - Test Tools Many Linux 2.28 #3 (CASA-CMDTF1-TTML228-3)'
... running command line:
/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-6/bamboohome/xml-data/build-dir/CASA-CMDTF1-TTML228
24-Apr-2023 11:51:11 wheel-container-test
24-Apr-2023 11:51:11 Running post build plugin 'NCover Results Collector'
24-Apr-2023 11:51:11 Running post build plugin 'Clover Results Collector'
24-Apr-2023 11:51:11 Running post build plugin 'npm Cache Cleanup'
24-Apr-2023 11:51:11 Running post build plugin 'Artifact Copier'
24-Apr-2023 11:51:11 Publishing an artifact: Workdir
24-Apr-2023 11:51:11 Unable to publish artifact [Workdir]:
24-Apr-2023 11:51:11 The artifact hasn't been successfully published after 76.50 ms
24-Apr-2023 11:51:11 Publishing an artifact: Tests
24-Apr-2023 11:51:11 Unable to publish artifact [Tests]:
24-Apr-2023 11:51:11 The artifact is required, build will now fail.
24-Apr-2023 11:51:11 The artifact hasn't been successfully published after 10.87 ms
24-Apr-2023 11:51:11 Finalising the build...
24-Apr-2023 11:51:11 Stopping timer.
24-Apr-2023 11:51:11 Build CASA-CMDTF1-TTML228-3 completed.
24-Apr-2023 11:51:11 Running on server: post build plugin 'NCover Results Collector'
24-Apr-2023 11:51:11 Running on server: post build plugin 'Build Hanging Detection Configuration'
24-Apr-2023 11:51:11 Running on server: post build plugin 'Clover Delta Calculator'
24-Apr-2023 11:51:11 Running on server: post build plugin 'Maven Dependencies Postprocessor'
24-Apr-2023 11:51:11 All post build plugins have finished
24-Apr-2023 11:51:11 Generating build results summary...
24-Apr-2023 11:51:11 Saving build results to disk...
24-Apr-2023 11:51:11 Store variable context...
24-Apr-2023 11:51:11 Indexing build results...
24-Apr-2023 11:51:11 Finished building CASA-CMDTF1-TTML228-3.
24-Apr-2023 11:51:13 Killing: 8519
24-Apr-2023 11:51:13 Executing kill 8519
24-Apr-2023 11:51:13 Killing: 8518
24-Apr-2023 11:51:13 Executing kill 8518
24-Apr-2023 11:51:18 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
24-Apr-2023 11:51:18 Has finished generating stack trace and terminating spawned sub-processes of process id: 14369