Tools and Tasks tests for Python 3.13

Build: #125 was successful

Job: Test Tools Many Linux 2.28 Rocky 8.10 Py3.13 was successful

Build log

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

26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 15 Scan : 30 : 30
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        FlagMSHandler::        Chunk = 17 [progress: 52%], Observation = 0, Array = 0, Scan = 31, Field = 1 (3C286_A), Spw = 0, Channels = 64, Corrs = [ RR RL LR LL ], Total Rows = 540
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 0 Scan : 31 : 31
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (179928/548352)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 1 Scan : 31 : 31
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 2 Scan : 31 : 31
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        FlagMSHandler::        Chunk = 20 [progress: 62%], Observation = 0, Array = 0, Scan = 31, Field = 1 (3C286_A), Spw = 3, Channels = 64, Corrs = [ RR RL LR LL ], Total Rows = 540
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 3 Scan : 31 : 31
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:48 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 4 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 5 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        FlagMSHandler::        Chunk = 23 [progress: 71%], Observation = 0, Array = 0, Scan = 31, Field = 1 (3C286_A), Spw = 6, Channels = 64, Corrs = [ RR RL LR LL ], Total Rows = 540
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 6 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 7 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 8 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        FlagMSHandler::        Chunk = 26 [progress: 81%], Observation = 0, Array = 0, Scan = 31, Field = 1 (3C286_A), Spw = 9, Channels = 64, Corrs = [ RR RL LR LL ], Total Rows = 540
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 9 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 10 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 11 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        FlagMSHandler::        Chunk = 29 [progress: 90%], Observation = 0, Array = 0, Scan = 31, Field = 1 (3C286_A), Spw = 12, Channels = 64, Corrs = [ RR RL LR LL ], Total Rows = 540
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 12 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 13 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:49        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 14 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        FlagMSHandler::        Chunk = 32 [progress: 100%], Observation = 0, Array = 0, Scan = 31, Field = 1 (3C286_A), Spw = 15, Channels = 64, Corrs = [ RR RL LR LL ], Total Rows = 540
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 15 Scan : 31 : 31
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        FlagMSHandler::nextChunk         ====================================================================================
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 32.8125%
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        AgentFlagger::run        => Writing flags to the MS
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          observation 0 flagged: 1.44346e+06 total: 4.3991e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          scan 31 flagged: 725760 total: 2.21184e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          scan 30 flagged: 717696 total: 2.18726e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          antenna ea24 flagged: 721728 total: 2.19955e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          antenna ea19 flagged: 721728 total: 2.19955e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          antenna ea01 flagged: 721728 total: 2.19955e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          antenna ea11 flagged: 721728 total: 2.19955e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          field 3C286_A flagged: 1.44346e+06 total: 4.3991e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 15 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 14 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 10 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 7 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 12 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 6 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 8 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 5 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 0 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 11 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 9 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 4 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 1 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 2 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 13 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          spw 3 flagged: 90216 total: 274944 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          correlation LL flagged: 360864 total: 1.09978e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          correlation LR flagged: 360864 total: 1.09978e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          correlation RR flagged: 360864 total: 1.09978e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          correlation RL flagged: 360864 total: 1.09978e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          array 0 flagged: 1.44346e+06 total: 4.3991e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Summary::getResult          Total Flagged: 1.44346e+06 Total Counts: 4.3991e+06 (32.8%)
26-Nov-2025 12:38:49 2025-11-26 17:38:50        INFO        Display::displayReports         Report Displays are turned OFF
26-Nov-2025 12:38:50 test_tool_agentflagger.py::test_display::test_display_data_single_channel_selection
26-Nov-2025 12:38:50 AgentFlagger:: Check nominal behaviour for single spw:chan selection 
26-Nov-2025 12:38:50 The MS is already around, just unflag
26-Nov-2025 12:38:50 PASSED
26-Nov-2025 12:38:50
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        AgentFlagger::open        Table type is Measurement Set
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: unflag
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: unflag
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (1028/1028)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2/2)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (1026/1026)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (4/4)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2050/2050)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (4096/4096)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (6/6)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2048/2048)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (4098/4098)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        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
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2/2)
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        FlagMSHandler::nextChunk         ====================================================================================
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        Unflag::tableSummary        => Percentage of data un-flagged in table selection: 100%
26-Nov-2025 12:38:50 2025-11-26 17:38:51        INFO        AgentFlagger::run        => Writing flags to the MS
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        AgentFlagger::open        Table type is Measurement Set
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [4, 4]  (NB: Matrix in Row/Column order)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        FlagMSHandler::selectData +        [1, 0, 511, 1
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        FlagMSHandler::selectData +         2, 0, 0, 1
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        FlagMSHandler::selectData +         3, 0, 511, 1
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        FlagMSHandler::selectData +         4, 0, 0, 1]
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        AgentFlagger::initAgents        There are initially 3 agents in the list. Agents: manual, summary, display
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Manual::setAgentParameters         autocorr is 0
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::setAgentParameters          data column is FLOAT_DATA
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::setAgentParameters          Visibility expression is REAL XX,YY
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::setAgentParameters          pause is 0
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::setAgentParameters          datadisplay is 1
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::setAgentParameters          reportdisplay is 0
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::setAgentParameters          format is screen
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        AgentFlagger::initAgents        There are 3 valid agents in the list. Agents: manual, summary, display
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        FlagDataHandler::preLoadColumn         Setting row blocking to number of rows in selected table: 4
26-Nov-2025 12:38:51 2025-11-26 17:38:51        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
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 1 Scan : 1 : 1
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
26-Nov-2025 12:38:51 /home/casatest/venv/bin/python3.13: No module named casaplotserver
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Manual::chunkSummary        => Data flagged so far 100% (1024/1024)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        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
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 2 Scan : 1 : 1
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Manual::chunkSummary        => Data flagged so far 100% (2/2)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        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
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 3 Scan : 1 : 1
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Manual::chunkSummary        => Data flagged so far 100% (1024/1024)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        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
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 4 Scan : 1 : 1
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Manual::chunkSummary        => Data flagged so far 100% (2/2)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        FlagMSHandler::nextChunk         ====================================================================================
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 100%
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        AgentFlagger::run        => Writing flags to the MS
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          observation 0 flagged: 2052 total: 2052 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          scan 1 flagged: 2052 total: 2052 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          antenna PM04 flagged: 2052 total: 2052 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          field r aqr flagged: 2052 total: 2052 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          spw 4 flagged: 2 total: 2 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          spw 3 flagged: 1024 total: 1024 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          spw 1 flagged: 1024 total: 1024 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          spw 2 flagged: 2 total: 2 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          correlation YY flagged: 1026 total: 1026 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          correlation XX flagged: 1026 total: 1026 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          array 0 flagged: 2052 total: 2052 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Summary::getResult          Total Flagged: 2052 Total Counts: 2052 (100%)
26-Nov-2025 12:38:51 2025-11-26 17:38:51        INFO        Display::displayReports         Report Displays are turned OFF
26-Nov-2025 12:38:51 test_tool_agentflagger.py::test_display::test_display_float_data
26-Nov-2025 12:38:51 AgentFlagger: Select spw, display and flag single-dish MS
26-Nov-2025 12:38:51 Moving data...
26-Nov-2025 12:38:51 PASSED
26-Nov-2025 12:38:51
26-Nov-2025 12:38:51 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_agentflagger/nose.xml -
26-Nov-2025 12:38:51 ======================== 35 passed, 1 warning in 34.25s ========================
26-Nov-2025 12:38:52 ============================= test session starts ==============================
26-Nov-2025 12:38:52 platform linux -- Python 3.13.2, pytest-9.0.1, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.13
26-Nov-2025 12:38:52 cachedir: .pytest_cache
26-Nov-2025 12:38:52 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation
26-Nov-2025 12:38:52 configfile: pytest.ini
26-Nov-2025 12:38:52 collecting ... collected 8 items
26-Nov-2025 12:38:52
26-Nov-2025 12:38:52
26-Nov-2025 12:38:52 2025-11-26 17:38:53        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 10000 grid points in 2 planes.
26-Nov-2025 12:38:53 2025-11-26 17:38:53        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:53 2025-11-26 17:38:53        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 10000 grid points in 2 planes.
26-Nov-2025 12:38:53 2025-11-26 17:38:54        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:53 2025-11-26 17:38:54        INFO        name::        No table opened.
26-Nov-2025 12:38:53 2025-11-26 17:38:54        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:38:53 2025-11-26 17:38:54        INFO        name::        No table opened.
26-Nov-2025 12:38:53 2025-11-26 17:38:54        INFO        name::        No table opened.
26-Nov-2025 12:38:53 test_tool_image_deviation.py::ia_deviation_test::test001
26-Nov-2025 12:38:53 Every pixel is a grid point
26-Nov-2025 12:38:53 PASSED
26-Nov-2025 12:38:53
26-Nov-2025 12:38:53 2025-11-26 17:38:54        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 10000 grid points in 2 planes.
26-Nov-2025 12:38:53 2025-11-26 17:38:54        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:53 2025-11-26 17:38:54        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 10000 grid points in 2 planes.
26-Nov-2025 12:38:54 2025-11-26 17:38:54        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:54 2025-11-26 17:38:54        INFO        name::        No table opened.
26-Nov-2025 12:38:54 2025-11-26 17:38:54        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:38:54 2025-11-26 17:38:54        INFO        name::        No table opened.
26-Nov-2025 12:38:54 2025-11-26 17:38:54        INFO        name::        No table opened.
26-Nov-2025 12:38:54 test_tool_image_deviation.py::ia_deviation_test::test002
26-Nov-2025 12:38:54 Every pixel is a grid point with an offset, so should be the same result
26-Nov-2025 12:38:54 as test001
26-Nov-2025 12:38:54 PASSED
26-Nov-2025 12:38:54
26-Nov-2025 12:38:54 2025-11-26 17:38:54        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 1156 grid points in 2 planes.
26-Nov-2025 12:38:54 2025-11-26 17:38:54        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:54 2025-11-26 17:38:54        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:54 2025-11-26 17:38:54        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 1156 grid points in 2 planes.
26-Nov-2025 12:38:54 2025-11-26 17:38:54        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:54 2025-11-26 17:38:54        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:54 2025-11-26 17:38:54        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:54 2025-11-26 17:38:54        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 1156 grid points in 2 planes.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 1156 grid points in 2 planes.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        name::        No table opened.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        name::        No table opened.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        name::        No table opened.
26-Nov-2025 12:38:54 test_tool_image_deviation.py::ia_deviation_test::test003
26-Nov-2025 12:38:54 Every third pixel is a grid point
26-Nov-2025 12:38:54 PASSED
26-Nov-2025 12:38:54
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 1089 grid points in 2 planes.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 1089 grid points in 2 planes.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:54 2025-11-26 17:38:55        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing NPTS using the classical algorithm around each of 1089 grid points in 2 planes.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 1089 grid points in 2 planes.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        name::        No table opened.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        name::        No table opened.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        name::        No table opened.
26-Nov-2025 12:38:55 test_tool_image_deviation.py::ia_deviation_test::test005
26-Nov-2025 12:38:55 Every third pixel is a grid point, using anchor [2,2]
26-Nov-2025 12:38:55 PASSED
26-Nov-2025 12:38:55
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 10.001 pix x 10.001 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 11 pix x 11 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 64 grid points in 2 planes.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        ImageRegion::fromRecord+            [5, 5, 0] to [85, 85, 1]  (00:03:00.021, -00.45.00.077, I to 23:57:39.990, +00.35.00.036, Q)
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 10.001 pix x 10.001 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 11 pix x 11 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 36 grid points in 2 planes.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        ImageRegion::fromRecord+            [27, 27, 0] to [66, 66, 0]  (00:01:32.003, -00.23.00.010, I to 23:58:55.999, +00.16.00.003, I)
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        ImageRegion::fromRecord+            [22, 22, 0] to [61, 61, 0]  (00:01:32.003, -00.23.00.010, I to 23:58:55.999, +00.16.00.003, I)
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        name::        No table opened.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        name::        No table opened.
26-Nov-2025 12:38:55 2025-11-26 17:38:55        INFO        name::        No table opened.
26-Nov-2025 12:38:55 test_tool_image_deviation.py::ia_deviation_test::test006
26-Nov-2025 12:38:55 Test that regions work as expected
26-Nov-2025 12:38:55 PASSED
26-Nov-2025 12:38:55
26-Nov-2025 12:38:55 2025-11-26 17:38:56        INFO        ImageFactory::createImage        Created Paged image 'mycirc.im' of shape [100, 100] with float valued pixels.
26-Nov-2025 12:38:55 2025-11-26 17:38:56        INFO        StatImageCreator::useReferencePixelAsAnchor        Anchor being set at pixel [50,50], at/near image reference pixel.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_computeStat        Using circular region of radius 20 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 41 pix x 41 pix and there are 1257 good pixels in the circle that are being used) to choose pixels for computing SUM using the classical algorithm around each of 25 grid points in 1 planes.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_doInterpolation        Interpolate using CUBIC algorithm.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        name::        No table opened.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        name::        No table opened.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        name::        No table opened.
26-Nov-2025 12:38:56 test_tool_image_deviation.py::ia_deviation_test::test_circle
26-Nov-2025 12:38:56 test circles work correctly CAS-10296
26-Nov-2025 12:38:56 PASSED
26-Nov-2025 12:38:56
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 10] with float valued pixels.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 9 grid points in 1 planes.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 10, 2] with float valued pixels.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 9 grid points in 2 planes.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_doInterpolation        Interpolate using LINEAR algorithm.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        name::        No table opened.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        name::        No table opened.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        name::        No table opened.
26-Nov-2025 12:38:56 test_tool_image_deviation.py::ia_deviation_test::test_mask
26-Nov-2025 12:38:56 PASSED
26-Nov-2025 12:38:56
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        ImageFactory::createImage        Created Temp image  of shape [20, 20] with float valued pixels.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 25 grid points in 1 planes.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_doInterpolation        Interpolate using CUBIC algorithm.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::useReferencePixelAsAnchor        Anchor being set at pixel [10,10], at/near image reference pixel.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_computeStat        Using rectangular region of specified dimensions 4 pix x 4 pix (because of centering and rounding to use whole pixels, actual dimensions of bounding box are 5 pix x 5 pix) to choose pixels for computing STDDEV using the classical algorithm around each of 25 grid points in 1 planes.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        StatImageCreator::_doInterpolation        Interpolate using CUBIC algorithm.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        name::        No table opened.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        name::        No table opened.
26-Nov-2025 12:38:56 2025-11-26 17:38:56        INFO        name::        No table opened.
26-Nov-2025 12:38:56 test_tool_image_deviation.py::ia_deviation_test::test_refpix
26-Nov-2025 12:38:56 Test using reference pixel
26-Nov-2025 12:38:56 PASSED
26-Nov-2025 12:38:56
26-Nov-2025 12:38:56 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_deviation/nose.xml -
26-Nov-2025 12:38:56 ========================= 8 passed, 1 warning in 3.99s =========================
26-Nov-2025 12:38:56 ============================= test session starts ==============================
26-Nov-2025 12:38:56 platform linux -- Python 3.13.2, pytest-9.0.1, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.13
26-Nov-2025 12:38:56 cachedir: .pytest_cache
26-Nov-2025 12:38:56 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_msuvbinner
26-Nov-2025 12:38:56 configfile: pytest.ini
26-Nov-2025 12:38:57 collecting ... collected 1 item
26-Nov-2025 12:38:57
26-Nov-2025 12:38:57
26-Nov-2025 12:39:05 test_tool_msuvbinner.py::msuvbinnerTest::test_fill_1ms
26-Nov-2025 12:39:05 test uvgridding 1 ms onto an output grid 
26-Nov-2025 12:39:05 PASSED
26-Nov-2025 12:39:05
26-Nov-2025 12:39:05 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_msuvbinner/nose.xml -
26-Nov-2025 12:39:05 ========================= 1 passed, 1 warning in 8.85s =========================
26-Nov-2025 12:39:06 ============================= test session starts ==============================
26-Nov-2025 12:39:06 platform linux -- Python 3.13.2, pytest-9.0.1, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.13
26-Nov-2025 12:39:06 cachedir: .pytest_cache
26-Nov-2025 12:39:06 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage
26-Nov-2025 12:39:06 configfile: pytest.ini
26-Nov-2025 12:39:06 collecting ... collected 5 items
26-Nov-2025 12:39:06
26-Nov-2025 12:39:06
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 10, 10, 4] with float valued pixels.
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        This image currently has no beams of any kind. Since channel and/or polarization were specified, a set of per plane beams, each equal to the specified beam, will be created.
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 4 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 2 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 2 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 1 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 3 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 2 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 4 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 3 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 5 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 4 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 3 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 2 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 4 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 3 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 5 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 4 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 6 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 5 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 4 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 3 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 5 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 4 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 6 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 5 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 7 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 6 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 5 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 4 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 6 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 5 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 7 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 6 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 8 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 7 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 6 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 5 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 7 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 6 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 8 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 7 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 9 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 8 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 7 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 6 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 8 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 7 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 9 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 8 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 10 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 9 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 8 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 7 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 9 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 8 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 10 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 9 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 11 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 10 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 9 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 8 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 10 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 9 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 11 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 10 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 12 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 11 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 10 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 9 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 11 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 10 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 12 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 11 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 13 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 12 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 11 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 10 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 12 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 11 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 13 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 12 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        image::_casaQuantityFromVar         Will use values specified in major, minor, pa to set beam
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set        Beam parameters:  Major          : 14 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Minor          : 13 arcsec
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        BeamManipulator::set+          Position Angle : 5 deg
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        ImageRegion::fromRecord+            [2, 2, 2, 2] to [5, 5, 5, 3]  (00:00:12.000, -00.03.00.000, 1.415e+09Hz, U to 00:00:00.000, +00.00.00.000, 1.415e+09Hz, V)
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        ImageRegion::fromRecord+            [2, 2, 2, 2] to [5, 5, 5, 2]  (00:00:12.000, -00.03.00.000, 1.415e+09Hz, U to 00:00:00.000, +00.00.00.000, 1.415e+09Hz, U)
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        ImageRegion::fromRecord+            [2, 2, 6, 1] to [5, 5, 6, 3]  (00:00:12.000, -00.03.00.000, 1.415e+09Hz, Q to 00:00:00.000, +00.00.00.000, 1.415e+09Hz, V)
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        name::        No table opened.
26-Nov-2025 12:39:06 2025-11-26 17:39:07        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:39:06 test_tool_image_subimage.py::ia_subimage_test::test_beams
26-Nov-2025 12:39:06 Test per plane beams 
26-Nov-2025 12:39:06 PASSED
26-Nov-2025 12:39:06
26-Nov-2025 12:39:07 2025-11-26 17:39:07        INFO        ImageFactory::createImage        Created Paged image 'zz.im' of shape [20, 20] with float valued pixels.
26-Nov-2025 12:39:07 2025-11-26 17:39:07        INFO        ::::ImageFactory::createImage        Created Paged image 'zz.im' of shape [20, 20] with float valued pixels.
26-Nov-2025 12:39:07 2025-11-26 17:39:07        INFO        ::::image::fromshape        Ran ia.fromshape
26-Nov-2025 12:39:07 2025-11-26 17:39:07        INFO        ::::image::fromshape        ia.fromshape(outfile="zz.im", shape=[20,20], csys={...}, linear=false, overwrite=false, log=true, type="f")
26-Nov-2025 12:39:07 2025-11-26 17:39:07        INFO        ::::image::subimage        Ran ia.subimage
26-Nov-2025 12:39:07 2025-11-26 17:39:07        INFO        ::::image::subimage        ia.subimage(outfile="", region="", mask="", dropdeg=false, overwrite=false, list=true, stretch=false, wantreturn=true, keepaxes=[])
26-Nov-2025 12:39:07 2025-11-26 17:39:08        INFO        ImageFactory::createImage        Created Paged image 'gg' of shape [20, 20] with float valued pixels.
26-Nov-2025 12:39:07 2025-11-26 17:39:08        INFO        ::::ImageFactory::createImage        Created Paged image 'gg' of shape [20, 20] with float valued pixels.
26-Nov-2025 12:39:08 2025-11-26 17:39:08        INFO        name::        No table opened.
26-Nov-2025 12:39:08 2025-11-26 17:39:08        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:39:08 test_tool_image_subimage.py::ia_subimage_test::test_history
26-Nov-2025 12:39:08 verify history writing
26-Nov-2025 12:39:08 PASSED
26-Nov-2025 12:39:08
26-Nov-2025 12:39:08 2025-11-26 17:39:08        INFO        ImageFactory::createImage        Created Temp image  of shape [10, 20, 30] with float valued pixels.
26-Nov-2025 12:39:08 2025-11-26 17:39:08        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:39:08 2025-11-26 17:39:08        INFO        ImageRegion::fromRecord+            [0, 0, 0] to [9, 19, 29]  (00:00:20.000, -00.10.00.001, 1.41498e+09Hz to 23:59:44.000, +00.09.00.001, 1.41501e+09Hz)
26-Nov-2025 12:39:08 2025-11-26 17:39:08        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:39:08 2025-11-26 17:39:08        INFO        ImageRegion::fromRecord+            [0, 0, 0] to [9, 19, 29]  (00:00:20.000, -00.10.00.001, 1.41498e+09Hz to 23:59:44.000, +00.09.00.001, 1.41501e+09Hz)
26-Nov-2025 12:39:08 2025-11-26 17:39:08        INFO        ImageFactory::createImage        Created Paged image 'keep.im' of shape [10, 20, 1, 1] with float valued pixels.
26-Nov-2025 12:39:08 2025-11-26 17:39:09        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:39:08 2025-11-26 17:39:09        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [9, 19, 0, 0]  (00:00:20.000, -00.10.00.001, I, 1.415e+09Hz to 23:59:44.000, +00.09.00.001, I, 1.415e+09Hz)
26-Nov-2025 12:39:09 2025-11-26 17:39:09        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:39:09 2025-11-26 17:39:09        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [9, 19, 0, 0]  (00:00:20.000, -00.10.00.001, I, 1.415e+09Hz to 23:59:44.000, +00.09.00.001, I, 1.415e+09Hz)
26-Nov-2025 12:39:09 2025-11-26 17:39:09        INFO        ImageRegion::fromRecord        Selected bounding box :
26-Nov-2025 12:39:09 2025-11-26 17:39:09        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [9, 19, 0, 0]  (00:00:20.000, -00.10.00.001, I, 1.415e+09Hz to 23:59:44.000, +00.09.00.001, I, 1.415e+09Hz)
26-Nov-2025 12:39:09 2025-11-26 17:39:10        INFO        name::        No table opened.
26-Nov-2025 12:39:09 2025-11-26 17:39:10        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:39:09 test_tool_image_subimage.py::ia_subimage_test::test_keepaxes
26-Nov-2025 12:39:09 Test the keepaxes parameter
26-Nov-2025 12:39:09 PASSED
26-Nov-2025 12:39:09
26-Nov-2025 12:39:09 2025-11-26 17:39:10        INFO        ImageFactory::createImage        Created Temp image  of shape [2, 2] with float valued pixels.
26-Nov-2025 12:39:09 2025-11-26 17:39:10        INFO        ImageFactory::createImage        Created Temp image  of shape [2, 2] with Complex valued pixels.
26-Nov-2025 12:39:09 2025-11-26 17:39:10        INFO        ImageFactory::createImage        Created Temp image  of shape [2, 2] with double valued pixels.
26-Nov-2025 12:39:10 2025-11-26 17:39:10        INFO        ImageFactory::createImage        Created Temp image  of shape [2, 2] with DComplex valued pixels.
26-Nov-2025 12:39:10 2025-11-26 17:39:10        INFO        name::        No table opened.
26-Nov-2025 12:39:10 2025-11-26 17:39:10        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:39:10 test_tool_image_subimage.py::ia_subimage_test::test_precision
26-Nov-2025 12:39:10 Test various precision valued image support
26-Nov-2025 12:39:10 PASSED
26-Nov-2025 12:39:10
26-Nov-2025 12:39:10 2025-11-26 17:39:11        INFO        ImageFactory::createImage        Created Paged image 'mask1.im' of shape [20, 30, 4, 10] with float valued pixels.
26-Nov-2025 12:39:11 2025-11-26 17:39:12        INFO        ImageFactory::createImage        Created Paged image 'mask2.im' of shape [20, 30, 4, 1] with float valued pixels.
26-Nov-2025 12:39:11 2025-11-26 17:39:12        INFO        ImageFactory::createImage        Created Paged image 'mask3.im' of shape [20, 30, 4, 2] with float valued pixels.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        INFO        ImageFactory::createImage        Created Paged image 'xx.im' of shape [20, 30, 4, 10] with float valued pixels.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
26-Nov-2025 12:39:12 2025-11-26 17:39:13        INFO        name::        No table opened.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        SEVERE        image::_detached (file src/tools/image/image_cmpt.cc, line 1653)        Image is detached - cannot perform operation.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        SEVERE        image::_detached (file src/tools/image/image_cmpt.cc, line 1653)+        Call image.open('filename') to reattach.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        SEVERE        image::_detached (file src/tools/image/image_cmpt.cc, line 1653)        Exception Reported: Exception: Unable to create image.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        SEVERE        image::_detached (file src/tools/image/image_cmpt.cc, line 1653)+        ... thrown by casac::image* casac::image::subimage(const string&, const casac::variant&, const casac::variant&, bool, bool, bool, bool, bool, const std::vector<long int>&) at File: src/tools/image/image_cmpt.cc, line: 6163
26-Nov-2025 12:39:12 2025-11-26 17:39:13        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
26-Nov-2025 12:39:12 2025-11-26 17:39:13        INFO        name::        No table opened.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        SEVERE        image::subimage (file src/tools/image/image_cmpt.cc, line 6162)        Exception Reported: Unable to extend mask: ExtendSpecifier - new nor stretch axes given at File: /source/casainstall/include/casacpp/imageanalysis/ImageAnalysis/SubImageFactory.tcc, line: 313
26-Nov-2025 12:39:12 2025-11-26 17:39:13        INFO        name::        No table opened.
26-Nov-2025 12:39:12 2025-11-26 17:39:13        INFO        showcache::        The Table Cache is empty.
26-Nov-2025 12:39:12 test_tool_image_subimage.py::ia_subimage_test::test_stretch
26-Nov-2025 12:39:12 Test the stretch parameter
26-Nov-2025 12:39:12 PASSED
26-Nov-2025 12:39:12
26-Nov-2025 12:39:12 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_subimage/nose.xml -
26-Nov-2025 12:39:12 ========================= 5 passed, 1 warning in 6.56s =========================
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_measures/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_measures/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pv/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pv
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_pv/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pv/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calibrater/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_ms/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_coordsys/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_coordsys
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_coordsys/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_coordsys/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_statwt/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_statwt
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_ms_statwt/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_statwt/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_regrid/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_regrid/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_statistics/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_quanta/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_quanta/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_componentlist/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_componentlist
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_componentlist/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_componentlist/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm_toms/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm_toms
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_sdm_toms/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm_toms/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_vpmanager/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_vpmanager
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_vpmanager/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_vpmanager/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_table/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_table
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_table/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_table/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_transpose/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_transpose
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_transpose/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_transpose/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_agentflagger/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_agentflagger
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_agentflagger/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_agentflagger/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_deviation/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_msuvbinner/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_msuvbinner
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_msuvbinner/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_msuvbinner/
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage/
26-Nov-2025 12:39:13 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage
26-Nov-2025 12:39:13
26-Nov-2025 12:39:13 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_subimage/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_subimage/
26-Nov-2025 12:39:16 total 77004
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci 15001   136803 Nov 26 17:00 casatestutils-6.7.3.9-py3-none-any.whl
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci 15001 78544189 Nov 26 17:00 casatools-6.7.3.9-cp313-cp313-manylinux_2_28_x86_64.whl
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      4340 Nov 26 17:39 test_tool_agentflagger.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1006 Nov 26 17:39 test_tool_calanalysis.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      2659 Nov 26 17:39 test_tool_calibrater.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       687 Nov 26 17:39 test_tool_componentlist.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      7467 Nov 26 17:39 test_tool_coordsys.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root     12940 Nov 26 17:39 test_tool_image.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      2221 Nov 26 17:39 test_tool_image_collapse.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1048 Nov 26 17:39 test_tool_image_deviation.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      2578 Nov 26 17:39 test_tool_image_fitprofile.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1179 Nov 26 17:39 test_tool_image_pbcor.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       885 Nov 26 17:39 test_tool_image_pv.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1006 Nov 26 17:39 test_tool_image_rebin.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1337 Nov 26 17:39 test_tool_image_regrid.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1533 Nov 26 17:39 test_tool_image_statistics.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       765 Nov 26 17:39 test_tool_image_subimage.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       891 Nov 26 17:39 test_tool_image_transpose.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1966 Nov 26 17:39 test_tool_imagepol.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       839 Nov 26 17:39 test_tool_linearmosaic.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       669 Nov 26 17:39 test_tool_logsink.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       549 Nov 26 17:39 test_tool_measures.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      5226 Nov 26 17:39 test_tool_ms.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       367 Nov 26 17:39 test_tool_ms_createmultims.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      2095 Nov 26 17:39 test_tool_ms_statwt.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      8634 Nov 26 17:39 test_tool_msmetadata.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       359 Nov 26 17:39 test_tool_msuvbinner.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1171 Nov 26 17:39 test_tool_quanta.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      3977 Nov 26 17:39 test_tool_regionmanager.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      2074 Nov 26 17:39 test_tool_sdm.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      2104 Nov 26 17:39 test_tool_sdm_toms.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root       670 Nov 26 17:39 test_tool_simulator.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1718 Nov 26 17:39 test_tool_synthesisutils.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1462 Nov 26 17:39 test_tool_table.xml
26-Nov-2025 12:39:16 -rw-r--r-- 1 casaci root      1719 Nov 26 17:39 test_tool_vpmanager.xml
26-Nov-2025 12:39:16 Finished task 'Test wheel' with result: Success
26-Nov-2025 12:39:16 Starting task 'Tar work directory' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
26-Nov-2025 12:39:16
Beginning to execute external process for build 'CASASPECS - Master Test 3.13 - Test Tools Many Linux 2.28 Rocky 8.10 Py3.13 #125 (CASASPECS-MASTERTESTPY313-TTOMLPY313-125)'
... running command line:
/export/home/cbt-el7-10/bamboohome/temp/CASASPECS-MASTERTESTPY313-TTOMLPY313-125-ScriptBuildTask-10157597688285410158.sh
... in: /export/home/cbt-el7-10/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY313-TTOMLPY313
26-Nov-2025 12:39:16 Package working directory
26-Nov-2025 12:39:16 tar: nosedir: Cannot stat: No such file or directory
26-Nov-2025 12:39:16 tar: Exiting with failure status due to previous errors
26-Nov-2025 12:39:17 total 77008
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci 15001   136803 Nov 26 17:00 casatestutils-6.7.3.9-py3-none-any.whl
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci 15001 78544189 Nov 26 17:00 casatools-6.7.3.9-cp313-cp313-manylinux_2_28_x86_64.whl
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      4340 Nov 26 17:39 test_tool_agentflagger.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1006 Nov 26 17:39 test_tool_calanalysis.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      2659 Nov 26 17:39 test_tool_calibrater.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       687 Nov 26 17:39 test_tool_componentlist.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      7467 Nov 26 17:39 test_tool_coordsys.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root     12940 Nov 26 17:39 test_tool_image.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      2221 Nov 26 17:39 test_tool_image_collapse.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1048 Nov 26 17:39 test_tool_image_deviation.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      2578 Nov 26 17:39 test_tool_image_fitprofile.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1179 Nov 26 17:39 test_tool_image_pbcor.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       885 Nov 26 17:39 test_tool_image_pv.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1006 Nov 26 17:39 test_tool_image_rebin.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1337 Nov 26 17:39 test_tool_image_regrid.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1533 Nov 26 17:39 test_tool_image_statistics.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       765 Nov 26 17:39 test_tool_image_subimage.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       891 Nov 26 17:39 test_tool_image_transpose.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1966 Nov 26 17:39 test_tool_imagepol.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       839 Nov 26 17:39 test_tool_linearmosaic.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       669 Nov 26 17:39 test_tool_logsink.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       549 Nov 26 17:39 test_tool_measures.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      5226 Nov 26 17:39 test_tool_ms.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       367 Nov 26 17:39 test_tool_ms_createmultims.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      2095 Nov 26 17:39 test_tool_ms_statwt.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      8634 Nov 26 17:39 test_tool_msmetadata.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       359 Nov 26 17:39 test_tool_msuvbinner.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1171 Nov 26 17:39 test_tool_quanta.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      3977 Nov 26 17:39 test_tool_regionmanager.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      2074 Nov 26 17:39 test_tool_sdm.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      2104 Nov 26 17:39 test_tool_sdm_toms.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root       670 Nov 26 17:39 test_tool_simulator.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1718 Nov 26 17:39 test_tool_synthesisutils.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1462 Nov 26 17:39 test_tool_table.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root      1719 Nov 26 17:39 test_tool_vpmanager.xml
26-Nov-2025 12:39:17 -rw-r--r-- 1 casaci root        45 Nov 26 17:39 work-manylinux228-3.13.tar.gz
26-Nov-2025 12:39:17 Done packaging working directory
26-Nov-2025 12:39:17 Finished task 'Tar work directory' with result: Success
26-Nov-2025 12:39:17 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
26-Nov-2025 12:39:17 Parsing test results under /export/home/cbt-el7-10/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY313-TTOMLPY313...
26-Nov-2025 12:39:17 Finished task 'Parse tests' with result: Success
26-Nov-2025 12:39:17 Starting task 'Upload wheel' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
26-Nov-2025 12:39:17
Beginning to execute external process for build 'CASASPECS - Master Test 3.13 - Test Tools Many Linux 2.28 Rocky 8.10 Py3.13 #125 (CASASPECS-MASTERTESTPY313-TTOMLPY313-125)'
... running command line:
/export/home/cbt-el7-10/bamboohome/temp/CASASPECS-MASTERTESTPY313-TTOMLPY313-125-ScriptBuildTask-16953068601036803785.sh
... in: /export/home/cbt-el7-10/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY313-TTOMLPY313
26-Nov-2025 12:39:26 Collecting twine
26-Nov-2025 12:39:26   Downloading twine-6.2.0-py3-none-any.whl (42 kB)
26-Nov-2025 12:39:27      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 42.7/42.7 kB 94.8 kB/s eta 0:00:00
26-Nov-2025 12:39:27 Collecting rich>=12.0.0
26-Nov-2025 12:39:27   Downloading rich-14.2.0-py3-none-any.whl (243 kB)
26-Nov-2025 12:39:27      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 243.4/243.4 kB 2.0 MB/s eta 0:00:00
26-Nov-2025 12:39:27 Collecting readme-renderer>=35.0
26-Nov-2025 12:39:27   Downloading readme_renderer-44.0-py3-none-any.whl (13 kB)
26-Nov-2025 12:39:28 Collecting requests-toolbelt!=0.9.0,>=0.8.0
26-Nov-2025 12:39:28   Downloading requests_toolbelt-1.0.0-py2.py3-none-any.whl (54 kB)
26-Nov-2025 12:39:28      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 54.5/54.5 kB 204.6 kB/s eta 0:00:00
26-Nov-2025 12:39:28 Collecting packaging>=24.0
26-Nov-2025 12:39:28   Downloading packaging-25.0-py3-none-any.whl (66 kB)
26-Nov-2025 12:39:28      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 484.1 kB/s eta 0:00:00
26-Nov-2025 12:39:28 Collecting rfc3986>=1.4.0
26-Nov-2025 12:39:28   Downloading rfc3986-2.0.0-py2.py3-none-any.whl (31 kB)
26-Nov-2025 12:39:28 Collecting urllib3>=1.26.0
26-Nov-2025 12:39:28   Downloading urllib3-2.5.0-py3-none-any.whl (129 kB)
26-Nov-2025 12:39:29      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 129.8/129.8 kB 1.7 MB/s eta 0:00:00
26-Nov-2025 12:39:29 Collecting id
26-Nov-2025 12:39:29   Downloading id-1.5.0-py3-none-any.whl (13 kB)
26-Nov-2025 12:39:29 Collecting keyring>=21.2.0
26-Nov-2025 12:39:29   Downloading keyring-25.7.0-py3-none-any.whl (39 kB)
26-Nov-2025 12:39:29 Collecting requests>=2.20
26-Nov-2025 12:39:29   Downloading requests-2.32.5-py3-none-any.whl (64 kB)
26-Nov-2025 12:39:30      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 64.7/64.7 kB 602.5 kB/s eta 0:00:00
26-Nov-2025 12:39:30 Collecting jeepney>=0.4.2
26-Nov-2025 12:39:30   Downloading jeepney-0.9.0-py3-none-any.whl (49 kB)
26-Nov-2025 12:39:30      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 49.0/49.0 kB 921.3 kB/s eta 0:00:00
26-Nov-2025 12:39:30 Collecting jaraco.context
26-Nov-2025 12:39:30   Downloading jaraco.context-6.0.1-py3-none-any.whl (6.8 kB)
26-Nov-2025 12:39:30 Collecting SecretStorage>=3.2
26-Nov-2025 12:39:30   Downloading secretstorage-3.5.0-py3-none-any.whl (15 kB)
26-Nov-2025 12:39:30 Collecting importlib_metadata>=4.11.4
26-Nov-2025 12:39:30   Downloading importlib_metadata-8.7.0-py3-none-any.whl (27 kB)
26-Nov-2025 12:39:31 Collecting jaraco.functools
26-Nov-2025 12:39:31   Downloading jaraco_functools-4.3.0-py3-none-any.whl (10 kB)
26-Nov-2025 12:39:31 Collecting jaraco.classes
26-Nov-2025 12:39:31   Downloading jaraco.classes-3.4.0-py3-none-any.whl (6.8 kB)
26-Nov-2025 12:39:31 Collecting Pygments>=2.5.1
26-Nov-2025 12:39:31   Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB)
26-Nov-2025 12:39:31      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 7.8 MB/s eta 0:00:00
26-Nov-2025 12:39:32 Collecting docutils>=0.21.2
26-Nov-2025 12:39:32   Downloading docutils-0.22.3-py3-none-any.whl (633 kB)
26-Nov-2025 12:39:32      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 633.0/633.0 kB 1.2 MB/s eta 0:00:00
26-Nov-2025 12:39:32 Collecting nh3>=0.2.14
26-Nov-2025 12:39:32   Downloading nh3-0.3.2-cp38-abi3-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (797 kB)
26-Nov-2025 12:39:33      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 797.2/797.2 kB 5.1 MB/s eta 0:00:00
26-Nov-2025 12:39:33 Collecting certifi>=2017.4.17
26-Nov-2025 12:39:33   Downloading certifi-2025.11.12-py3-none-any.whl (159 kB)
26-Nov-2025 12:39:33      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 159.4/159.4 kB 1.1 MB/s eta 0:00:00
26-Nov-2025 12:39:33 Collecting idna<4,>=2.5
26-Nov-2025 12:39:33   Downloading idna-3.11-py3-none-any.whl (71 kB)
26-Nov-2025 12:39:33      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 71.0/71.0 kB 512.0 kB/s eta 0:00:00
26-Nov-2025 12:39:34 Collecting charset_normalizer<4,>=2
26-Nov-2025 12:39:34   Downloading charset_normalizer-3.4.4-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (153 kB)
26-Nov-2025 12:39:34      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 153.6/153.6 kB 998.2 kB/s eta 0:00:00
26-Nov-2025 12:39:34 Collecting markdown-it-py>=2.2.0
26-Nov-2025 12:39:34   Downloading markdown_it_py-4.0.0-py3-none-any.whl (87 kB)
26-Nov-2025 12:39:35      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 87.3/87.3 kB 488.9 kB/s eta 0:00:00
26-Nov-2025 12:39:35 Collecting zipp>=3.20
26-Nov-2025 12:39:35   Downloading zipp-3.23.0-py3-none-any.whl (10 kB)
26-Nov-2025 12:39:36 Collecting mdurl~=0.1
26-Nov-2025 12:39:36   Downloading mdurl-0.1.2-py3-none-any.whl (10.0 kB)
26-Nov-2025 12:39:36 Collecting cryptography>=2.0
26-Nov-2025 12:39:37   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_28_x86_64.whl (4.5 MB)
26-Nov-2025 12:39:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 14.2 MB/s eta 0:00:00
26-Nov-2025 12:39:37 Collecting more-itertools
26-Nov-2025 12:39:37   Downloading more_itertools-10.8.0-py3-none-any.whl (69 kB)
26-Nov-2025 12:39:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 69.7/69.7 kB 962.8 kB/s eta 0:00:00
26-Nov-2025 12:39:38 Collecting backports.tarfile
26-Nov-2025 12:39:38   Downloading backports.tarfile-1.2.0-py3-none-any.whl (30 kB)
26-Nov-2025 12:39:38 Collecting typing-extensions>=4.13.2
26-Nov-2025 12:39:38   Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB)
26-Nov-2025 12:39:38      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 675.9 kB/s eta 0:00:00
26-Nov-2025 12:39:39 Collecting cffi>=2.0.0
26-Nov-2025 12:39:39   Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB)
26-Nov-2025 12:39:39      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 216.5/216.5 kB 564.4 kB/s eta 0:00:00
26-Nov-2025 12:39:39 Collecting pycparser
26-Nov-2025 12:39:39   Downloading pycparser-2.23-py3-none-any.whl (118 kB)
26-Nov-2025 12:39:40      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 118.1/118.1 kB 240.4 kB/s eta 0:00:00
26-Nov-2025 12:39:40 Installing collected packages: zipp, urllib3, typing-extensions, rfc3986, Pygments, pycparser, packaging, nh3, more-itertools, mdurl, jeepney, idna, docutils, charset_normalizer, certifi, backports.tarfile, requests, readme-renderer, markdown-it-py, jaraco.functools, jaraco.context, jaraco.classes, importlib_metadata, cffi, rich, requests-toolbelt, id, cryptography, SecretStorage, keyring, twine
26-Nov-2025 12:39:51 Successfully installed Pygments-2.19.2 SecretStorage-3.5.0 backports.tarfile-1.2.0 certifi-2025.11.12 cffi-2.0.0 charset_normalizer-3.4.4 cryptography-46.0.3 docutils-0.22.3 id-1.5.0 idna-3.11 importlib_metadata-8.7.0 jaraco.classes-3.4.0 jaraco.context-6.0.1 jaraco.functools-4.3.0 jeepney-0.9.0 keyring-25.7.0 markdown-it-py-4.0.0 mdurl-0.1.2 more-itertools-10.8.0 nh3-0.3.2 packaging-25.0 pycparser-2.23 readme-renderer-44.0 requests-2.32.5 requests-toolbelt-1.0.0 rfc3986-2.0.0 rich-14.2.0 twine-6.2.0 typing-extensions-4.15.0 urllib3-2.5.0 zipp-3.23.0
26-Nov-2025 12:39:51 WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
26-Nov-2025 12:39:52
26-Nov-2025 12:39:52 [notice] A new release of pip is available: 23.0.1 -> 25.3
26-Nov-2025 12:39:52 [notice] To update, run: pip3 install --upgrade pip
26-Nov-2025 12:39:52 Cloning into 'casa-build-utils'...
26-Nov-2025 12:39:54 Uploading distributions to
26-Nov-2025 12:39:54 https://casa-pip.nrao.edu:443/repository/casa-test-wheel/
26-Nov-2025 12:39:55 Uploading casatools-6.7.3.9-cp313-cp313-manylinux_2_28_x86_64.whl
26-Nov-2025 12:39:55
26-Nov-2025 12:39:55   0% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 0.0/78.6 MB--:--?
26-Nov-2025 12:39:55   5% ━╸━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 3.9/78.6 MB00:0269.7 MB/s
26-Nov-2025 12:39:55 15% ━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 11.6/78.6 MB00:0183.5 MB/s
26-Nov-2025 12:39:55 17% ━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 13.7/78.6 MB00:0255.0 MB/s
26-Nov-2025 12:39:55 22% ━━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 17.5/78.6 MB00:0247.9 MB/s
26-Nov-2025 12:39:55 26% ━━━━━━━━━━╺━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 20.2/78.6 MB00:0240.5 MB/s
26-Nov-2025 12:39:55 28% ━━━━━━━━━━━╺━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 21.9/78.6 MB00:0232.3 MB/s
26-Nov-2025 12:39:56 32% ━━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━━━━ 25.4/78.6 MB00:0228.4 MB/s
26-Nov-2025 12:39:56 37% ━━━━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━━ 28.8/78.6 MB00:0229.2 MB/s
26-Nov-2025 12:39:56 40% ━━━━━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━ 31.2/78.6 MB00:0229.1 MB/s
26-Nov-2025 12:39:56 43% ━━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━━━━━━━━━━━ 34.1/78.6 MB00:0229.3 MB/s
26-Nov-2025 12:39:56 47% ━━━━━━━━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━ 36.7/78.6 MB00:0231.1 MB/s
26-Nov-2025 12:39:56 50% ━━━━━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━━━━━━━━ 39.3/78.6 MB00:0228.2 MB/s
26-Nov-2025 12:39:56 54% ━━━━━━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━━━━━━━ 42.1/78.6 MB00:0228.2 MB/s
26-Nov-2025 12:39:56 57% ━━━━━━━━━━━━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━ 44.6/78.6 MB00:0227.7 MB/s
26-Nov-2025 12:39:56 60% ━━━━━━━━━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━━━━ 47.4/78.6 MB00:0225.2 MB/s
26-Nov-2025 12:39:56 64% ━━━━━━━━━━━━━━━━━━━━━━━━━╸━━━━━━━━━━━━━━ 50.5/78.6 MB00:0227.6 MB/s
26-Nov-2025 12:39:57 68% ━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━ 53.4/78.6 MB00:0127.7 MB/s
26-Nov-2025 12:39:57 72% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━╸━━━━━━━━━━━ 56.3/78.6 MB00:0127.0 MB/s
26-Nov-2025 12:39:57 76% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━━━━━━━━━ 59.8/78.6 MB00:0127.3 MB/s
26-Nov-2025 12:39:57 80% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━━━━━━━ 63.0/78.6 MB00:0127.1 MB/s
26-Nov-2025 12:39:57 85% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━━━━━ 66.8/78.6 MB00:0130.9 MB/s
26-Nov-2025 12:39:57 88% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━━━━ 69.3/78.6 MB00:0130.5 MB/s
26-Nov-2025 12:39:57 92% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╸━━━ 72.3/78.6 MB00:0128.0 MB/s
26-Nov-2025 12:39:57 95% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━ 74.9/78.6 MB00:0131.8 MB/s
26-Nov-2025 12:39:57 98% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╺ 77.4/78.6 MB00:0128.3 MB/s
26-Nov-2025 12:39:58 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0228.3 MB/s
26-Nov-2025 12:39:58 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0228.3 MB/s
26-Nov-2025 12:39:58 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0228.3 MB/s
26-Nov-2025 12:39:58 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0228.3 MB/s
26-Nov-2025 12:39:58 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0228.3 MB/s
26-Nov-2025 12:39:58 Uploading distributions to
26-Nov-2025 12:39:58 https://casa-pip.nrao.edu:443/repository/casa-test-wheel/
26-Nov-2025 12:39:59 Uploading casatestutils-6.7.3.9-py3-none-any.whl
26-Nov-2025 12:39:59
26-Nov-2025 12:39:59   0% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 0.0/150.1 kB--:--?
26-Nov-2025 12:39:59 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 150.1/150.1 kB00:0054.2 MB/s
26-Nov-2025 12:39:59 WARNING Error during upload. Retry with the --verbose option for more details.
26-Nov-2025 12:39:59 ERROR    HTTPError: 400 Bad Request from                                       
26-Nov-2025 12:39:59          https://casa-pip.nrao.edu:443/repository/casa-test-wheel/             
26-Nov-2025 12:39:59          Repository does not allow updating assets: casa-test-wheel             
26-Nov-2025 12:40:01 Finished task 'Upload wheel' with result: Success
26-Nov-2025 12:40:01 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
26-Nov-2025 12:40:01
Beginning to execute external process for build 'CASASPECS - Master Test 3.13 - Test Tools Many Linux 2.28 Rocky 8.10 Py3.13 #125 (CASASPECS-MASTERTESTPY313-TTOMLPY313-125)'
... running command line:
/export/home/cbt-el7-10/bamboohome/temp/CASASPECS-MASTERTESTPY313-TTOMLPY313-125-ScriptBuildTask-12237286065274613556.sh
... in: /export/home/cbt-el7-10/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY313-TTOMLPY313
26-Nov-2025 12:40:01 Starting cleanup
26-Nov-2025 12:40:19 wheel-container-test
26-Nov-2025 12:40:23 wheel-container-test
26-Nov-2025 12:40:23 Cleanup complete
26-Nov-2025 12:40:23 Finished task 'Cleanup' with result: Success
26-Nov-2025 12:40:23 Running post build plugin 'Docker Container Cleanup'
26-Nov-2025 12:40:23
Beginning to execute external process for build 'CASASPECS - Master Test 3.13 - Test Tools Many Linux 2.28 Rocky 8.10 Py3.13 #125 (CASASPECS-MASTERTESTPY313-TTOMLPY313-125)'
... running command line:
/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-10/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY313-TTOMLPY313
26-Nov-2025 12:40:23 Error response from daemon: No such container: wheel-container-test
26-Nov-2025 12:40:23 Running post build plugin 'NCover Results Collector'
26-Nov-2025 12:40:23 Running post build plugin 'Build Results Label Collector'
26-Nov-2025 12:40:23 Running post build plugin 'Clover Results Collector'
26-Nov-2025 12:40:23 Running post build plugin 'npm Cache Cleanup'
26-Nov-2025 12:40:23 Running post build plugin 'Artifact Copier'
26-Nov-2025 12:40:23 Publishing an artifact: Workdir
26-Nov-2025 12:40:23 Finished publishing of artifact Non required job artifact Http Compression On : [Workdir], patterns: [work*.tar.gz] anchored at: [wheeldirectory/] in 346.9 ms
26-Nov-2025 12:40:23 Publishing an artifact: XML Directories
26-Nov-2025 12:40:23 Finished publishing of artifact Non required job artifact Http Compression On : [XML Directories], patterns: [**/*.xml] anchored at: [wheeldirectory/] in 267.3 ms
26-Nov-2025 12:40:23 Finalising the build...
26-Nov-2025 12:40:23 Stopping timer.
26-Nov-2025 12:40:23 Build CASASPECS-MASTERTESTPY313-TTOMLPY313-125 completed.
26-Nov-2025 12:40:24 Running on server: post build plugin 'Build Hanging Detection Configuration'
26-Nov-2025 12:40:24 Running on server: post build plugin 'NCover Results Collector'
26-Nov-2025 12:40:24 Running on server: post build plugin 'Build Labeller'
26-Nov-2025 12:40:24 Running on server: post build plugin 'Clover Delta Calculator'
26-Nov-2025 12:40:24 Running on server: post build plugin 'Maven Dependencies Postprocessor'
26-Nov-2025 12:40:24 All post build plugins have finished
26-Nov-2025 12:40:24 Generating build results summary...
26-Nov-2025 12:40:24 Saving build results to disk...
26-Nov-2025 12:40:24 Store variable context...
26-Nov-2025 12:40:24 Finished building CASASPECS-MASTERTESTPY313-TTOMLPY313-125.