2022-05-25 17:03:07        INFO        Summary::getResult          spw 3 flagged: 90216 total: 274944 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          spw 0 flagged: 90216 total: 274944 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          array 0 flagged: 1.44346e+06 total: 4.3991e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          antenna ea24 flagged: 721728 total: 2.19955e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          antenna ea19 flagged: 721728 total: 2.19955e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          antenna ea11 flagged: 721728 total: 2.19955e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          antenna ea01 flagged: 721728 total: 2.19955e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          field 3C286_A flagged: 1.44346e+06 total: 4.3991e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          scan 31 flagged: 725760 total: 2.21184e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          scan 30 flagged: 717696 total: 2.18726e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          correlation LL flagged: 360864 total: 1.09978e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          correlation RL flagged: 360864 total: 1.09978e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          correlation LR flagged: 360864 total: 1.09978e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          correlation RR flagged: 360864 total: 1.09978e+06 (32.8%)
2022-05-25 17:03:07        INFO        Summary::getResult          Total Flagged: 1.44346e+06 Total Counts: 4.3991e+06 (32.8%)
2022-05-25 17:03:07        INFO        Display::displayReports         Report Displays are turned OFF
2022-05-25 17:03:07        INFO        AgentFlagger::open        Table type is Measurement Set
2022-05-25 17:03:07        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: unflag
2022-05-25 17:03:07        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: unflag
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 1 [progress: 6%], Observation = 0, Array = 0, Scan = 1, Field = 0 (r aqr), Spw = 0, Channels = 4, Corrs = [ XX ], Total Rows = 1
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 2 [progress: 13%], Observation = 0, Array = 0, Scan = 1, Field = 0 (r aqr), Spw = 1, Channels = 512, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (1028/1028)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 3 [progress: 20%], Observation = 0, Array = 0, Scan = 1, Field = 0 (r aqr), Spw = 2, Channels = 1, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2/2)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 5 [progress: 33%], Observation = 0, Array = 0, Scan = 1, Field = 0 (r aqr), Spw = 4, Channels = 1, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (1026/1026)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 6 [progress: 40%], Observation = 0, Array = 0, Scan = 2, Field = 0 (r aqr), Spw = 0, Channels = 4, Corrs = [ XX ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (4/4)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 8 [progress: 53%], Observation = 0, Array = 0, Scan = 2, Field = 0 (r aqr), Spw = 6, Channels = 1, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2050/2050)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 9 [progress: 60%], Observation = 0, Array = 0, Scan = 2, Field = 0 (r aqr), Spw = 7, Channels = 2048, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (4096/4096)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 11 [progress: 73%], Observation = 0, Array = 0, Scan = 3, Field = 0 (r aqr), Spw = 0, Channels = 4, Corrs = [ XX ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (6/6)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 12 [progress: 80%], Observation = 0, Array = 0, Scan = 3, Field = 0 (r aqr), Spw = 5, Channels = 1024, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2048/2048)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 14 [progress: 93%], Observation = 0, Array = 0, Scan = 3, Field = 0 (r aqr), Spw = 7, Channels = 2048, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (4098/4098)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 15 [progress: 100%], Observation = 0, Array = 0, Scan = 3, Field = 0 (r aqr), Spw = 8, Channels = 1, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2/2)
2022-05-25 17:03:07        INFO        FlagMSHandler::nextChunk         ====================================================================================
2022-05-25 17:03:07        INFO        Unflag::tableSummary        => Percentage of data un-flagged in table selection: 100%
2022-05-25 17:03:07        INFO        AgentFlagger::run        => Writing flags to the MS
2022-05-25 17:03:07        INFO        AgentFlagger::open        Table type is Measurement Set
2022-05-25 17:03:07        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [4, 4]  (NB: Matrix in Row/Column order)
2022-05-25 17:03:07        INFO        FlagMSHandler::selectData +        [1, 0, 511, 1
2022-05-25 17:03:07        INFO        FlagMSHandler::selectData +         2, 0, 0, 1
2022-05-25 17:03:07        INFO        FlagMSHandler::selectData +         3, 0, 511, 1
2022-05-25 17:03:07        INFO        FlagMSHandler::selectData +         4, 0, 0, 1]
2022-05-25 17:03:07        INFO        AgentFlagger::initAgents        There are initially 3 agents in the list. Agents: manual, summary, display
2022-05-25 17:03:07        INFO        Manual::setAgentParameters         autocorr is 0
2022-05-25 17:03:07        INFO        Display::setAgentParameters          data column is FLOAT_DATA
2022-05-25 17:03:07        INFO        Display::setAgentParameters          Visibility expression is REAL XX,YY
2022-05-25 17:03:07        INFO        Display::setAgentParameters          pause is 0
2022-05-25 17:03:07        INFO        Display::setAgentParameters          datadisplay is 1
2022-05-25 17:03:07        INFO        Display::setAgentParameters          reportdisplay is 0
2022-05-25 17:03:07        INFO        Display::setAgentParameters          format is screen
2022-05-25 17:03:07        INFO        AgentFlagger::initAgents        There are 3 valid agents in the list. Agents: manual, summary, display
2022-05-25 17:03:07        INFO        FlagDataHandler::preLoadColumn         Setting row blocking to number of rows in selected table: 4
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 1 [progress: 25%], Observation = 0, Array = 0, Scan = 1, Field = 0 (r aqr), Spw = 1, Channels = 512, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 1 Scan : 1 : 1
2022-05-25 17:03:07        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF178-TTO15/casa6testenv/bin/python: No module named casaplotserver
2022-05-25 17:03:07        INFO        Manual::chunkSummary        => Data flagged so far 100% (1024/1024)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 2 [progress: 50%], Observation = 0, Array = 0, Scan = 1, Field = 0 (r aqr), Spw = 2, Channels = 1, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 2 Scan : 1 : 1
2022-05-25 17:03:07        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
2022-05-25 17:03:07        INFO        Manual::chunkSummary        => Data flagged so far 100% (2/2)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 3 [progress: 75%], Observation = 0, Array = 0, Scan = 1, Field = 0 (r aqr), Spw = 3, Channels = 512, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 3 Scan : 1 : 1
2022-05-25 17:03:07        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
2022-05-25 17:03:07        INFO        Manual::chunkSummary        => Data flagged so far 100% (1024/1024)
2022-05-25 17:03:07        INFO        FlagMSHandler::        Chunk = 4 [progress: 100%], Observation = 0, Array = 0, Scan = 1, Field = 0 (r aqr), Spw = 4, Channels = 1, Corrs = [ XX YY ], Total Rows = 1
2022-05-25 17:03:07        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 4 Scan : 1 : 1
2022-05-25 17:03:07        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
2022-05-25 17:03:07        INFO        Manual::chunkSummary        => Data flagged so far 100% (2/2)
2022-05-25 17:03:07        INFO        FlagMSHandler::nextChunk         ====================================================================================
2022-05-25 17:03:07        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 100%
2022-05-25 17:03:07        INFO        AgentFlagger::run        => Writing flags to the MS
2022-05-25 17:03:07        INFO        Summary::getResult          observation 0 flagged: 2052 total: 2052 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          spw 4 flagged: 2 total: 2 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          spw 3 flagged: 1024 total: 1024 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          spw 2 flagged: 2 total: 2 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          spw 1 flagged: 1024 total: 1024 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          array 0 flagged: 2052 total: 2052 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          antenna PM04 flagged: 2052 total: 2052 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          field r aqr flagged: 2052 total: 2052 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          scan 1 flagged: 2052 total: 2052 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          correlation YY flagged: 1026 total: 1026 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          correlation XX flagged: 1026 total: 1026 (100%)
2022-05-25 17:03:07        INFO        Summary::getResult          Total Flagged: 2052 Total Counts: 2052 (100%)
2022-05-25 17:03:07        INFO        Display::displayReports         Report Displays are turned OFF
Traceback (most recent call last):
  File "runtest.py", line 580, in run
    shutil.copy2("{}{}.py".format(workdir + "tests/",test), workdir + "{}/".format(test if not test.endswith(".py") else test[:-3]))
  File "/opt/casa/03/Library/Frameworks/Python.framework/Versions/3.6/lib/python3.6/shutil.py", line 263, in copy2
    copyfile(src, dst, follow_symlinks=follow_symlinks)
  File "/opt/casa/03/Library/Frameworks/Python.framework/Versions/3.6/lib/python3.6/shutil.py", line 120, in copyfile
    with open(src, 'rb') as fsrc:
FileNotFoundError: [Errno 2] No such file or directory: '/Users/casaci/bamboohome/xml-data/build-dir/CASA-C6DTF178-TTO15/casa6/casatestutils/nosedir/tests/test_tool_image_fromcomplist.py'
Cloning into 'casa-build-utils'...
You are using pip version 18.1, however version 21.3.1 is available.
You should consider upgrading via the 'pip install --upgrade pip' command.
HTTPError: 400 Bad Request from https://casa-pip.nrao.edu:443/repository/casa-dev-wheel/
Repository does not allow updating assets: casa-dev-wheel
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100    70  100    70    0     0   2692      0 --:--:-- --:--:-- --:--:--  2692