Build: #3 was successful

Job: Test Tools Many Linux 2.28 Py3.12 was successful

Build log

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

03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 4 Scan : 31 : 31
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 5 Scan : 31 : 31
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        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
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 6 Scan : 31 : 31
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 7 Scan : 31 : 31
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:33 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 8 Scan : 31 : 31
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 9 Scan : 31 : 31
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 10 Scan : 31 : 31
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 11 Scan : 31 : 31
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 12 Scan : 31 : 31
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 13 Scan : 31 : 31
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 14 Scan : 31 : 31
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 1 , 3C286_A Spw : 15 Scan : 31 : 31
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,1)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (1,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (2,3)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::chunkSummary        => Data flagged so far 32.8125% (136080/414720)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        FlagMSHandler::nextChunk         ====================================================================================
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 32.8125%
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        AgentFlagger::run        => Writing flags to the MS
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          observation 0 flagged: 1.44346e+06 total: 4.3991e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          scan 31 flagged: 725760 total: 2.21184e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          scan 30 flagged: 717696 total: 2.18726e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          antenna ea24 flagged: 721728 total: 2.19955e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          antenna ea19 flagged: 721728 total: 2.19955e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          antenna ea01 flagged: 721728 total: 2.19955e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          antenna ea11 flagged: 721728 total: 2.19955e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          field 3C286_A flagged: 1.44346e+06 total: 4.3991e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 15 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 14 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 10 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 7 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 12 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 6 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 8 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 5 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 0 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 11 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 9 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 4 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 1 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 2 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 13 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 3 flagged: 90216 total: 274944 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          correlation LL flagged: 360864 total: 1.09978e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          correlation LR flagged: 360864 total: 1.09978e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          correlation RR flagged: 360864 total: 1.09978e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          correlation RL flagged: 360864 total: 1.09978e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          array 0 flagged: 1.44346e+06 total: 4.3991e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          Total Flagged: 1.44346e+06 Total Counts: 4.3991e+06 (32.8%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::displayReports         Report Displays are turned OFF
03-Dec-2025 11:10:34 test_tool_agentflagger.py::test_display::test_display_data_single_channel_selection
03-Dec-2025 11:10:34 AgentFlagger:: Check nominal behaviour for single spw:chan selection 
03-Dec-2025 11:10:34 The MS is already around, just unflag
03-Dec-2025 11:10:34 PASSED
03-Dec-2025 11:10:34
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        AgentFlagger::open        Table type is Measurement Set
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        AgentFlagger::initAgents        There are initially 1 agents in the list. Agents: unflag
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        AgentFlagger::initAgents        There are 1 valid agents in the list. Agents: unflag
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (1028/1028)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2/2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (1026/1026)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (4/4)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2050/2050)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (4096/4096)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (6/6)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2048/2048)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (4098/4098)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::chunkSummary        => Data unflagged so far 100% (2/2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        FlagMSHandler::nextChunk         ====================================================================================
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Unflag::tableSummary        => Percentage of data un-flagged in table selection: 100%
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        AgentFlagger::run        => Writing flags to the MS
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        AgentFlagger::open        Table type is Measurement Set
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        FlagMSHandler::selectData          Selected spw-channels ids are Axis Lengths: [4, 4]  (NB: Matrix in Row/Column order)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        FlagMSHandler::selectData +        [1, 0, 511, 1
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        FlagMSHandler::selectData +         2, 0, 0, 1
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        FlagMSHandler::selectData +         3, 0, 511, 1
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        FlagMSHandler::selectData +         4, 0, 0, 1]
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        AgentFlagger::initAgents        There are initially 3 agents in the list. Agents: manual, summary, display
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::setAgentParameters         autocorr is 0
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::setAgentParameters          data column is FLOAT_DATA
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::setAgentParameters          Visibility expression is REAL XX,YY
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::setAgentParameters          pause is 0
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::setAgentParameters          datadisplay is 1
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::setAgentParameters          reportdisplay is 0
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::setAgentParameters          format is screen
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        AgentFlagger::initAgents        There are 3 valid agents in the list. Agents: manual, summary, display
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        FlagDataHandler::preLoadColumn         Setting row blocking to number of rows in selected table: 4
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 1 Scan : 1 : 1
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
03-Dec-2025 11:10:34 /home/casatest/venv/bin/python3.12: No module named casaplotserver
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::chunkSummary        => Data flagged so far 100% (1024/1024)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 2 Scan : 1 : 1
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::chunkSummary        => Data flagged so far 100% (2/2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 3 Scan : 1 : 1
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::chunkSummary        => Data flagged so far 100% (1024/1024)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        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
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::getChunkInfo         FlagAgentDisplay::getChunkInfo Field : 0 , r aqr Spw : 4 Scan : 1 : 1
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::processAntennaPair          Going to process requested baseline (0,0)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::chunkSummary        => Data flagged so far 100% (2/2)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        FlagMSHandler::nextChunk         ====================================================================================
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Manual::tableSummary        => Percentage of data flagged in table selection: 100%
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        AgentFlagger::run        => Writing flags to the MS
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          observation 0 flagged: 2052 total: 2052 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          scan 1 flagged: 2052 total: 2052 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          antenna PM04 flagged: 2052 total: 2052 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          field r aqr flagged: 2052 total: 2052 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 4 flagged: 2 total: 2 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 3 flagged: 1024 total: 1024 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 1 flagged: 1024 total: 1024 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          spw 2 flagged: 2 total: 2 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          correlation YY flagged: 1026 total: 1026 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          correlation XX flagged: 1026 total: 1026 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          array 0 flagged: 2052 total: 2052 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Summary::getResult          Total Flagged: 2052 Total Counts: 2052 (100%)
03-Dec-2025 11:10:34 2025-12-03 16:10:34        INFO        Display::displayReports         Report Displays are turned OFF
03-Dec-2025 11:10:34 test_tool_agentflagger.py::test_display::test_display_float_data
03-Dec-2025 11:10:34 AgentFlagger: Select spw, display and flag single-dish MS
03-Dec-2025 11:10:34 Moving data...
03-Dec-2025 11:10:34 PASSED
03-Dec-2025 11:10:34
03-Dec-2025 11:10:34 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_agentflagger/nose.xml -
03-Dec-2025 11:10:34 ======================== 35 passed, 1 warning in 41.83s ========================
03-Dec-2025 11:10:34 ============================= test session starts ==============================
03-Dec-2025 11:10:34 platform linux -- Python 3.12.9, pytest-9.0.1, pluggy-1.6.0 -- /home/casatest/venv/bin/python3.12
03-Dec-2025 11:10:34 cachedir: .pytest_cache
03-Dec-2025 11:10:34 rootdir: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor
03-Dec-2025 11:10:34 configfile: pytest.ini
03-Dec-2025 11:10:35 collecting ... collected 9 items
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 0, 0]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, I)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        name::        No table opened.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        showcache::        The Table Cache is empty.
03-Dec-2025 11:10:35 test_tool_image_pbcor.py::ia_pbcor_test::test_4d_image_with_2d_pb
03-Dec-2025 11:10:35 ia.pbcor: Test full image divide with cutoff. Primary beam is 2 D, image is 4 D
03-Dec-2025 11:10:35 PASSED
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [5, 5, 1, 1]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        name::        No table opened.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        showcache::        The Table Cache is empty.
03-Dec-2025 11:10:35 test_tool_image_pbcor.py::ia_pbcor_test::test_diff_spectral_coordinate
03-Dec-2025 11:10:35 Verify fix that a different spectral coordinates in target and template don't matter, CAS-5096
03-Dec-2025 11:10:35 PASSED
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageFactory::createImage        Created Paged image 't_in.im' of shape [20, 20, 4, 20] with float valued pixels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageFactory::createImage        Created Paged image 't_pb_in.im' of shape [20, 20, 4, 20] with float valued pixels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using polarizations I
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [19, 19, 0, 19]  (00:00:40.000, -00.10.00.001, I, 1.41499e+09Hz to 23:59:24.000, +00.09.00.001, I, 1.41501e+09Hz)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [19, 19, 0, 19]  (00:00:40.000, -00.10.00.001, I, 1.41499e+09Hz to 23:59:24.000, +00.09.00.001, I, 1.41501e+09Hz)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 't_out.im' of shape [20, 20, 1, 20]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        name::        No table opened.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        showcache::        The Table Cache is empty.
03-Dec-2025 11:10:35 test_tool_image_pbcor.py::ia_pbcor_test::test_empty_region_and_stokes
03-Dec-2025 11:10:35 Test specifying stokes with empty region works (CAS-11708)
03-Dec-2025 11:10:35 PASSED
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Exception Reported: Exception: File name is empty.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::open (file src/tools/image/image_cmpt.cc, line 4208)+        ... thrown by static void casa::ImageFactory::_checkInfile(const casacore::String&) at File: /source/casa6/casatools/src/code/imageanalysis/ImageAnalysis/ImageFactory2.cc, line: 371
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Exception Reported: Exception: File totally_bogus does not exist..
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::open (file src/tools/image/image_cmpt.cc, line 4208)+        ... thrown by static void casa::ImageFactory::_checkInfile(const casacore::String&) at File: /source/casa6/casatools/src/code/imageanalysis/ImageAnalysis/ImageFactory2.cc, line: 375
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Exception: The image filename is empty.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)+        ... thrown by static void casacore::ImageUtilities::openImage(casacore::ImageInterface<T>*&, const casacore::String&) [with T = float] at File: /source/casainstall/include/casacore/images/Images/ImageUtilities2.tcc, line: 255
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Exception: File 'totally_bogus2' does not exist.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)+        ... thrown by static void casacore::ImageUtilities::openImage(casacore::ImageInterface<T>*&, const casacore::String&) [with T = float] at File: /source/casainstall/include/casacore/images/Images/ImageUtilities2.tcc, line: 260
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        ImageInputProcessor::_process        Requested output image /bogusplace/bogusimage cannot be created so will not be written
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: 2025-12-03 16:10:35        SEVERE        ImageInputProcessor::_process        Requested output image /bogusplace/bogusimage cannot be created so will not be written
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        WARN                /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/pbtest1_im.fits is not a valid or readable table
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE                Cannot read image /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/pbtest1_im.fits to get region bogus_region
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Unable to open region file or region table description bogus_region in image /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/pbtest1_im.fits at File: /source/casa6/casatools/src/code/imageanalysis/Regions/CasacRegionManager.cc, line: 486
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Unknown mode zz
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: Coordinate systems of image and template are different: The DirectionCoordinates have differing LinearXform components at File: /source/casa6/casatools/src/code/imageanalysis/ImageAnalysis/ImagePrimaryBeamCorrector.cc, line: 141
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        name::        No table opened.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        showcache::        The Table Cache is empty.
03-Dec-2025 11:10:35 test_tool_image_pbcor.py::ia_pbcor_test::test_exceptions
03-Dec-2025 11:10:35 ia.pbcor: Test various exception cases
03-Dec-2025 11:10:35 PASSED
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        name::        No table opened.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        showcache::        The Table Cache is empty.
03-Dec-2025 11:10:35 test_tool_image_pbcor.py::ia_pbcor_test::test_full_image_divide
03-Dec-2025 11:10:35 ia.pbcor: Test full image divide
03-Dec-2025 11:10:35 PASSED
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0] to [112, 75]  (00:00:29.333, -00.07.24.000 to 23:59:29.600, +00.07.36.000)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        name::        No table opened.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        showcache::        The Table Cache is empty.
03-Dec-2025 11:10:35 test_tool_image_pbcor.py::ia_pbcor_test::test_full_image_using_cutoff
03-Dec-2025 11:10:35 ia.pbcor: Test full image divide with cutoff
03-Dec-2025 11:10:35 PASSED
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageFactory::createImage        Created Paged image 'zz.im' of shape [20, 20] with float valued pixels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'pb_out.im' of shape [20, 20]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ::::ImageFactory::createImage        Created Paged image 'zz.im' of shape [20, 20] with float valued pixels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ::::image::fromshape        Ran ia.fromshape
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ::::image::fromshape        ia.fromshape(outfile="zz.im", shape=[20,20], csys={...}, linear=false, overwrite=false, log=true, type="f")
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ::::image::pbcor        Ran ia.pbcor
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ::::image::pbcor        ia.pbcor(pbimage="(...)", outfile="pb_out.im", overwrite=false, box="", region={...}, chans="", stokes="", mask="", mode="divide", cutoff=-1.000000, stretch=false)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        name::        No table opened.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        showcache::        The Table Cache is empty.
03-Dec-2025 11:10:35 test_tool_image_pbcor.py::ia_pbcor_test::test_history
03-Dec-2025 11:10:35 Test history records are written
03-Dec-2025 11:10:35 PASSED
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageFactory::createImage        Created Paged image 'mult_pb.im' of shape [113, 76] with float valued pixels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 0, 0]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, I)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        No directional region specified. Using full positional plane.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using all spectral channels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        CasacRegionManager::fromBCS        Using polarizations ALL
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord        Selected bounding box :
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageRegion::fromRecord+            [0, 0, 0, 0] to [112, 75, 4, 1]  (00:03:44.024, -00.38.00.046, 1.415e+09Hz, I to 23:56:15.977, +00.37.00.043, 1.415e+09Hz, Q)
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'mypb.im' of shape [113, 76, 5, 2]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        name::        No table opened.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        showcache::        The Table Cache is empty.
03-Dec-2025 11:10:35 test_tool_image_pbcor.py::ia_pbcor_test::test_multiply
03-Dec-2025 11:10:35 ia.pbcor: Test full image multiply with cutoff. Primary beam is 2 D, image is 4 D
03-Dec-2025 11:10:35 PASSED
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageFactory::createImage        Created Temp image  of shape [113, 76, 1, 1] with float valued pixels.
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'maskim' of shape [113, 76, 1, 1]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        SEVERE        image::pbcor (file src/tools/image/image_cmpt.cc, line 4289)        Exception Reported: The input image shape ([113, 76, 5, 2]) and mask shape ([113, 76, 1, 1]) are different, and it was specified that the mask should not be extended, so the mask cannot be applied to the (sub)image. Specifying that the mask should be extended may resolve the issue at File: /source/casainstall/include/casacpp/imageanalysis/ImageAnalysis/SubImageFactory.tcc, line: 306
03-Dec-2025 11:10:35 2025-12-03 16:10:35        WARN        image::open (file src/tools/image/image_cmpt.cc, line 4208)        Another image is already open, closing first
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        FITSCoordinateUtil::fromFITSHeader        passing empty or nonexistant spectral Coordinate axis
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        SubImageFactory::createImage        Creating image 'blahblah' of shape [113, 76, 5, 2]
03-Dec-2025 11:10:35 2025-12-03 16:10:35        INFO        ImageMaskAttacher::makeMask        Created mask `mask0'
03-Dec-2025 11:10:35 2025-12-03 16:10:36        INFO        name::        No table opened.
03-Dec-2025 11:10:35 2025-12-03 16:10:36        INFO        showcache::        The Table Cache is empty.
03-Dec-2025 11:10:35 test_tool_image_pbcor.py::ia_pbcor_test::test_stretch
03-Dec-2025 11:10:35 ia.pbcor(): Test stretch parameter
03-Dec-2025 11:10:35 PASSED
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 - generated xml file: /home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_pbcor/nose.xml -
03-Dec-2025 11:10:35 ========================= 9 passed, 1 warning in 0.81s =========================
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_deviation
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 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/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm_toms/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm_toms
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 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/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_statistics
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 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/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_createmultims/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_createmultims
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_ms_createmultims/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_ms_createmultims/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_collapse/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_collapse
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_collapse/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_collapse/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calibrater
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 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/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_ms
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 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/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_regionmanager/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_regionmanager/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_measures
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 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/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_fitprofile/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_fitprofile
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_fitprofile/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_fitprofile/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_calanalysis/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_calanalysis/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_msuvbinner/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_msuvbinner
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 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/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_sdm/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_sdm/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_transpose/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_transpose
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 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/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_simulator/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_simulator/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_agentflagger/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_agentflagger
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 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/
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Setting Working Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/
03-Dec-2025 11:10:35 Test Directory: /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor
03-Dec-2025 11:10:35
03-Dec-2025 11:10:35 Running Command: pytest --junitxml=/home/casatest/casa6/casatestutils/nosedir/xml/test_tool_image_pbcor/nose.xml -s --disable-pytest-warnings --verbose -ra --tb=short /home/casatest/casa6/casatestutils/nosedir/test_tool_image_pbcor/
03-Dec-2025 11:10:35 total 77004
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci 15001   136743 Dec  3 15:59 casatestutils-6.7.3.2a14681.dev3-py3-none-any.whl
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci 15001 78544265 Dec  3 15:59 casatools-6.7.3.2a14681.dev3-cp312-cp312-manylinux_2_28_x86_64.whl
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      4340 Dec  3 16:10 test_tool_agentflagger.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1006 Dec  3 16:10 test_tool_calanalysis.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      2667 Dec  3 16:10 test_tool_calibrater.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       687 Dec  3 16:10 test_tool_componentlist.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      7467 Dec  3 16:10 test_tool_coordsys.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root     12935 Dec  3 16:10 test_tool_image.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      2220 Dec  3 16:10 test_tool_image_collapse.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1048 Dec  3 16:10 test_tool_image_deviation.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      2574 Dec  3 16:10 test_tool_image_fitprofile.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1178 Dec  3 16:10 test_tool_image_pbcor.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       883 Dec  3 16:10 test_tool_image_pv.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1006 Dec  3 16:10 test_tool_image_rebin.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1336 Dec  3 16:10 test_tool_image_regrid.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1533 Dec  3 16:10 test_tool_image_statistics.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       765 Dec  3 16:10 test_tool_image_subimage.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       890 Dec  3 16:10 test_tool_image_transpose.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1966 Dec  3 16:10 test_tool_imagepol.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       838 Dec  3 16:10 test_tool_linearmosaic.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       669 Dec  3 16:10 test_tool_logsink.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       549 Dec  3 16:10 test_tool_measures.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      5224 Dec  3 16:10 test_tool_ms.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       367 Dec  3 16:10 test_tool_ms_createmultims.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      2099 Dec  3 16:10 test_tool_ms_statwt.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      8633 Dec  3 16:10 test_tool_msmetadata.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       359 Dec  3 16:10 test_tool_msuvbinner.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1171 Dec  3 16:10 test_tool_quanta.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      3975 Dec  3 16:10 test_tool_regionmanager.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      2071 Dec  3 16:10 test_tool_sdm.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      2092 Dec  3 16:10 test_tool_sdm_toms.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root       670 Dec  3 16:10 test_tool_simulator.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1716 Dec  3 16:10 test_tool_synthesisutils.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1462 Dec  3 16:10 test_tool_table.xml
03-Dec-2025 11:10:35 -rw-r--r-- 1 casaci root      1717 Dec  3 16:10 test_tool_vpmanager.xml
03-Dec-2025 11:10:35 Finished task 'Test wheel' with result: Success
03-Dec-2025 11:10:35
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:35
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:35
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:35
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:35 Starting task 'Tar work directory' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
03-Dec-2025 11:10:35
Beginning to execute external process for build 'CASASPECS - Dev Test 3.12 - CAS-14681 - Test Tools Many Linux 2.28 Py3.12 #3 (CASASPECS-DEVTESTPY31244-TTOMLPY312-3)'
... running command line:
/opt/ci_workspace/casa/bamboo/agent2/temp/CASASPECS-DEVTESTPY31244-TTOMLPY312-3-ScriptBuildTask-6424607838416364864.sh
... in: /opt/ci_workspace/casa/bamboo/agent2/xml-data/build-dir/CASASPECS-DEVTESTPY31244-TTOMLPY312
03-Dec-2025 11:10:35 Package working directory
03-Dec-2025 11:10:36 nosedir/
03-Dec-2025 11:10:36 nosedir/test_tool_ms_statwt/
03-Dec-2025 11:10:36 nosedir/test_tool_ms_statwt/test_tool_ms_statwt.py
03-Dec-2025 11:10:36 nosedir/test_tool_ms_statwt/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_ms_statwt/casa-20251203-160110.log
03-Dec-2025 11:10:36 nosedir/test_tool_ms_statwt/short_summary.log
03-Dec-2025 11:10:36 nosedir/xml/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_ms_statwt/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_ms_statwt/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_vpmanager/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_vpmanager/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_rebin/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_rebin/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_linearmosaic/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_linearmosaic/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_imagepol/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_imagepol/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_componentlist/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_componentlist/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_regrid/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_regrid/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_quanta/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_quanta/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_coordsys/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_coordsys/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_msmetadata/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_msmetadata/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_pv/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_pv/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_subimage/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_subimage/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_table/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_table/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_synthesisutils/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_synthesisutils/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_logsink/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_logsink/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_deviation/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_deviation/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_sdm_toms/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_sdm_toms/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_statistics/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_statistics/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_ms_createmultims/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_ms_createmultims/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_collapse/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_collapse/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_calibrater/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_calibrater/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_ms/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_ms/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_regionmanager/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_regionmanager/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_measures/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_measures/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_fitprofile/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_fitprofile/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_calanalysis/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_calanalysis/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_msuvbinner/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_msuvbinner/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_sdm/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_sdm/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_transpose/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_transpose/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_simulator/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_simulator/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_agentflagger/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_agentflagger/nose.xml
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_pbcor/
03-Dec-2025 11:10:36 nosedir/xml/test_tool_image_pbcor/nose.xml
03-Dec-2025 11:10:36 nosedir/test_tool_vpmanager/
03-Dec-2025 11:10:36 nosedir/test_tool_vpmanager/test_tool_vpmanager.py
03-Dec-2025 11:10:36 nosedir/test_tool_vpmanager/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_vpmanager/casa-20251203-160320.log
03-Dec-2025 11:10:36 nosedir/test_tool_vpmanager/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_rebin/
03-Dec-2025 11:10:36 nosedir/test_tool_image_rebin/test_tool_image_rebin.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_rebin/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_rebin/casa-20251203-160324.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_rebin/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_linearmosaic/
03-Dec-2025 11:10:36 nosedir/test_tool_linearmosaic/test_tool_linearmosaic.py
03-Dec-2025 11:10:36 nosedir/test_tool_linearmosaic/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_linearmosaic/casa-20251203-160325.log
03-Dec-2025 11:10:36 nosedir/test_tool_linearmosaic/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_imagepol/
03-Dec-2025 11:10:36 nosedir/test_tool_imagepol/test_tool_imagepol.py
03-Dec-2025 11:10:36 nosedir/test_tool_imagepol/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_imagepol/casa-20251203-160331.log
03-Dec-2025 11:10:36 nosedir/test_tool_imagepol/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_componentlist/
03-Dec-2025 11:10:36 nosedir/test_tool_componentlist/test_tool_componentlist.py
03-Dec-2025 11:10:36 nosedir/test_tool_componentlist/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_componentlist/casa-20251203-160332.log
03-Dec-2025 11:10:36 nosedir/test_tool_componentlist/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_regrid/
03-Dec-2025 11:10:36 nosedir/test_tool_image_regrid/test_tool_image_regrid.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_regrid/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_regrid/casa-20251203-160332.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_regrid/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_quanta/
03-Dec-2025 11:10:36 nosedir/test_tool_quanta/test_tool_quanta.py
03-Dec-2025 11:10:36 nosedir/test_tool_quanta/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_quanta/casa-20251203-160334.log
03-Dec-2025 11:10:36 nosedir/test_tool_quanta/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_coordsys/
03-Dec-2025 11:10:36 nosedir/test_tool_coordsys/test_tool_coordsys.py
03-Dec-2025 11:10:36 nosedir/test_tool_coordsys/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_coordsys/casa-20251203-160334.log
03-Dec-2025 11:10:36 nosedir/test_tool_coordsys/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_msmetadata/
03-Dec-2025 11:10:36 nosedir/test_tool_msmetadata/test_tool_msmetadata.py
03-Dec-2025 11:10:36 nosedir/test_tool_msmetadata/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_msmetadata/casa-20251203-160335.log
03-Dec-2025 11:10:36 nosedir/test_tool_msmetadata/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_pv/
03-Dec-2025 11:10:36 nosedir/test_tool_image_pv/test_tool_image_pv.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_pv/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_pv/casa-20251203-160338.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_pv/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_subimage/
03-Dec-2025 11:10:36 nosedir/test_tool_image_subimage/test_tool_image_subimage.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_subimage/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_subimage/casa-20251203-160339.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_subimage/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_table/
03-Dec-2025 11:10:36 nosedir/test_tool_table/test_tool_table.py
03-Dec-2025 11:10:36 nosedir/test_tool_table/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_table/casa-20251203-160340.log
03-Dec-2025 11:10:36 nosedir/test_tool_table/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_synthesisutils/
03-Dec-2025 11:10:36 nosedir/test_tool_synthesisutils/test_tool_synthesisutils.py
03-Dec-2025 11:10:36 nosedir/test_tool_synthesisutils/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_synthesisutils/casa-20251203-160341.log
03-Dec-2025 11:10:36 nosedir/test_tool_synthesisutils/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_logsink/
03-Dec-2025 11:10:36 nosedir/test_tool_logsink/test_tool_logsink.py
03-Dec-2025 11:10:36 nosedir/test_tool_logsink/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_logsink/casa-20251203-160346.log
03-Dec-2025 11:10:36 nosedir/test_tool_logsink/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_deviation/
03-Dec-2025 11:10:36 nosedir/test_tool_image_deviation/test_tool_image_deviation.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_deviation/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_deviation/casa-20251203-160346.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_deviation/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_sdm_toms/
03-Dec-2025 11:10:36 nosedir/test_tool_sdm_toms/test_tool_sdm_toms.py
03-Dec-2025 11:10:36 nosedir/test_tool_sdm_toms/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_sdm_toms/casa-20251203-160350.log
03-Dec-2025 11:10:36 nosedir/test_tool_sdm_toms/short_summary.log
03-Dec-2025 11:10:36 nosedir/summary_of_failed.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_statistics/
03-Dec-2025 11:10:36 nosedir/test_tool_image_statistics/test_tool_image_statistics.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_statistics/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_statistics/casa-20251203-160553.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_statistics/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_ms_createmultims/
03-Dec-2025 11:10:36 nosedir/test_tool_ms_createmultims/test_tool_ms_createmultims.py
03-Dec-2025 11:10:36 nosedir/test_tool_ms_createmultims/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_ms_createmultims/casa-20251203-160608.log
03-Dec-2025 11:10:36 nosedir/test_tool_ms_createmultims/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_collapse/
03-Dec-2025 11:10:36 nosedir/test_tool_image_collapse/test_tool_image_collapse.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_collapse/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_collapse/casa-20251203-160611.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_collapse/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_calibrater/
03-Dec-2025 11:10:36 nosedir/test_tool_calibrater/test_tool_calibrater.py
03-Dec-2025 11:10:36 nosedir/test_tool_calibrater/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_calibrater/casa-20251203-160612.log
03-Dec-2025 11:10:36 nosedir/test_tool_calibrater/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_ms/
03-Dec-2025 11:10:36 nosedir/test_tool_ms/test_tool_ms.py
03-Dec-2025 11:10:36 nosedir/test_tool_ms/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_ms/casa-20251203-160828.log
03-Dec-2025 11:10:36 nosedir/test_tool_ms/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_regionmanager/
03-Dec-2025 11:10:36 nosedir/test_tool_regionmanager/test_tool_regionmanager.py
03-Dec-2025 11:10:36 nosedir/test_tool_regionmanager/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_regionmanager/casa-20251203-160853.log
03-Dec-2025 11:10:36 nosedir/test_tool_regionmanager/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_measures/
03-Dec-2025 11:10:36 nosedir/test_tool_measures/test_tool_measures.py
03-Dec-2025 11:10:36 nosedir/test_tool_measures/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_measures/casa-20251203-160900.log
03-Dec-2025 11:10:36 nosedir/test_tool_measures/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_fitprofile/
03-Dec-2025 11:10:36 nosedir/test_tool_image_fitprofile/test_tool_image_fitprofile.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_fitprofile/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_fitprofile/casa-20251203-160901.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_fitprofile/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_calanalysis/
03-Dec-2025 11:10:36 nosedir/test_tool_calanalysis/test_tool_calanalysis.py
03-Dec-2025 11:10:36 nosedir/test_tool_calanalysis/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_calanalysis/casa-20251203-160906.log
03-Dec-2025 11:10:36 nosedir/test_tool_calanalysis/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_msuvbinner/
03-Dec-2025 11:10:36 nosedir/test_tool_msuvbinner/test_tool_msuvbinner.py
03-Dec-2025 11:10:36 nosedir/test_tool_msuvbinner/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_msuvbinner/casa-20251203-160907.log
03-Dec-2025 11:10:36 nosedir/test_tool_msuvbinner/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_sdm/
03-Dec-2025 11:10:36 nosedir/test_tool_sdm/test_tool_sdm.py
03-Dec-2025 11:10:36 nosedir/test_tool_sdm/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_sdm/casa-20251203-160909.log
03-Dec-2025 11:10:36 nosedir/test_tool_sdm/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_transpose/
03-Dec-2025 11:10:36 nosedir/test_tool_image_transpose/test_tool_image_transpose.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_transpose/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_transpose/casa-20251203-160916.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_transpose/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image/
03-Dec-2025 11:10:36 nosedir/test_tool_image/test_tool_image.py
03-Dec-2025 11:10:36 nosedir/test_tool_image/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image/casa-20251203-160916.log
03-Dec-2025 11:10:36 nosedir/test_tool_image/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_simulator/
03-Dec-2025 11:10:36 nosedir/test_tool_simulator/test_tool_simulator.py
03-Dec-2025 11:10:36 nosedir/test_tool_simulator/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_simulator/casa-20251203-160947.log
03-Dec-2025 11:10:36 nosedir/test_tool_simulator/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_agentflagger/
03-Dec-2025 11:10:36 nosedir/test_tool_agentflagger/test_tool_agentflagger.py
03-Dec-2025 11:10:36 nosedir/test_tool_agentflagger/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_agentflagger/casa-20251203-160952.log
03-Dec-2025 11:10:36 nosedir/test_tool_agentflagger/short_summary.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_pbcor/
03-Dec-2025 11:10:36 nosedir/test_tool_image_pbcor/test_tool_image_pbcor.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_pbcor/conftest.py
03-Dec-2025 11:10:36 nosedir/test_tool_image_pbcor/casa-20251203-161035.log
03-Dec-2025 11:10:36 nosedir/test_tool_image_pbcor/short_summary.log
03-Dec-2025 11:10:36 total 77188
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci 15001   136743 Dec  3 15:59 casatestutils-6.7.3.2a14681.dev3-py3-none-any.whl
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci 15001 78544265 Dec  3 15:59 casatools-6.7.3.2a14681.dev3-cp312-cp312-manylinux_2_28_x86_64.whl
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      4340 Dec  3 16:10 test_tool_agentflagger.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1006 Dec  3 16:10 test_tool_calanalysis.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      2667 Dec  3 16:10 test_tool_calibrater.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       687 Dec  3 16:10 test_tool_componentlist.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      7467 Dec  3 16:10 test_tool_coordsys.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root     12935 Dec  3 16:10 test_tool_image.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      2220 Dec  3 16:10 test_tool_image_collapse.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1048 Dec  3 16:10 test_tool_image_deviation.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      2574 Dec  3 16:10 test_tool_image_fitprofile.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1178 Dec  3 16:10 test_tool_image_pbcor.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       883 Dec  3 16:10 test_tool_image_pv.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1006 Dec  3 16:10 test_tool_image_rebin.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1336 Dec  3 16:10 test_tool_image_regrid.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1533 Dec  3 16:10 test_tool_image_statistics.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       765 Dec  3 16:10 test_tool_image_subimage.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       890 Dec  3 16:10 test_tool_image_transpose.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1966 Dec  3 16:10 test_tool_imagepol.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       838 Dec  3 16:10 test_tool_linearmosaic.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       669 Dec  3 16:10 test_tool_logsink.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       549 Dec  3 16:10 test_tool_measures.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      5224 Dec  3 16:10 test_tool_ms.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       367 Dec  3 16:10 test_tool_ms_createmultims.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      2099 Dec  3 16:10 test_tool_ms_statwt.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      8633 Dec  3 16:10 test_tool_msmetadata.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       359 Dec  3 16:10 test_tool_msuvbinner.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1171 Dec  3 16:10 test_tool_quanta.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      3975 Dec  3 16:10 test_tool_regionmanager.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      2071 Dec  3 16:10 test_tool_sdm.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      2092 Dec  3 16:10 test_tool_sdm_toms.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root       670 Dec  3 16:10 test_tool_simulator.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1716 Dec  3 16:10 test_tool_synthesisutils.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1462 Dec  3 16:10 test_tool_table.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root      1717 Dec  3 16:10 test_tool_vpmanager.xml
03-Dec-2025 11:10:36 -rw-r--r-- 1 casaci root    185919 Dec  3 16:10 work-manylinux_228_casatools-3.12.tar.gz
03-Dec-2025 11:10:36 Done packaging working directory
03-Dec-2025 11:10:36 Finished task 'Tar work directory' with result: Success
03-Dec-2025 11:10:36 Starting task 'Parse tests' of type 'com.atlassian.bamboo.plugins.testresultparser:task.testresultparser.junit'
03-Dec-2025 11:10:36 Parsing test results under /opt/ci_workspace/casa/bamboo/agent2/xml-data/build-dir/CASASPECS-DEVTESTPY31244-TTOMLPY312...
03-Dec-2025 11:10:36 Finished task 'Parse tests' with result: Success
03-Dec-2025 11:10:36
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:36
Substituting variable: ${bamboo.repository.branch.name} with CAS-14681
03-Dec-2025 11:10:36
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:36
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:36
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:36
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:36
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:36 Starting task 'Upload wheel' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
03-Dec-2025 11:10:36
Beginning to execute external process for build 'CASASPECS - Dev Test 3.12 - CAS-14681 - Test Tools Many Linux 2.28 Py3.12 #3 (CASASPECS-DEVTESTPY31244-TTOMLPY312-3)'
... running command line:
/opt/ci_workspace/casa/bamboo/agent2/temp/CASASPECS-DEVTESTPY31244-TTOMLPY312-3-ScriptBuildTask-17757505285136412213.sh
... in: /opt/ci_workspace/casa/bamboo/agent2/xml-data/build-dir/CASASPECS-DEVTESTPY31244-TTOMLPY312
03-Dec-2025 11:10:36 Collecting twine
03-Dec-2025 11:10:36   Downloading twine-6.2.0-py3-none-any.whl (42 kB)
03-Dec-2025 11:10:36      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 42.7/42.7 kB 212.7 kB/s eta 0:00:00
03-Dec-2025 11:10:36 Collecting packaging>=24.0
03-Dec-2025 11:10:36   Downloading packaging-25.0-py3-none-any.whl (66 kB)
03-Dec-2025 11:10:36      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 16.0 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting rich>=12.0.0
03-Dec-2025 11:10:37   Downloading rich-14.2.0-py3-none-any.whl (243 kB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 243.4/243.4 kB 39.2 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting requests>=2.20
03-Dec-2025 11:10:37   Downloading requests-2.32.5-py3-none-any.whl (64 kB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 64.7/64.7 kB 31.7 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting urllib3>=1.26.0
03-Dec-2025 11:10:37   Downloading urllib3-2.5.0-py3-none-any.whl (129 kB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 129.8/129.8 kB 50.2 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting id
03-Dec-2025 11:10:37   Downloading id-1.5.0-py3-none-any.whl (13 kB)
03-Dec-2025 11:10:37 Collecting keyring>=21.2.0
03-Dec-2025 11:10:37   Downloading keyring-25.7.0-py3-none-any.whl (39 kB)
03-Dec-2025 11:10:37 Collecting readme-renderer>=35.0
03-Dec-2025 11:10:37   Downloading readme_renderer-44.0-py3-none-any.whl (13 kB)
03-Dec-2025 11:10:37 Collecting rfc3986>=1.4.0
03-Dec-2025 11:10:37   Downloading rfc3986-2.0.0-py2.py3-none-any.whl (31 kB)
03-Dec-2025 11:10:37 Collecting requests-toolbelt!=0.9.0,>=0.8.0
03-Dec-2025 11:10:37   Downloading requests_toolbelt-1.0.0-py2.py3-none-any.whl (54 kB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 54.5/54.5 kB 19.4 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting jaraco.context
03-Dec-2025 11:10:37   Downloading jaraco.context-6.0.1-py3-none-any.whl (6.8 kB)
03-Dec-2025 11:10:37 Collecting jeepney>=0.4.2
03-Dec-2025 11:10:37   Downloading jeepney-0.9.0-py3-none-any.whl (49 kB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 49.0/49.0 kB 21.5 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting jaraco.classes
03-Dec-2025 11:10:37   Downloading jaraco.classes-3.4.0-py3-none-any.whl (6.8 kB)
03-Dec-2025 11:10:37 Collecting importlib_metadata>=4.11.4
03-Dec-2025 11:10:37   Downloading importlib_metadata-8.7.0-py3-none-any.whl (27 kB)
03-Dec-2025 11:10:37 Collecting SecretStorage>=3.2
03-Dec-2025 11:10:37   Downloading secretstorage-3.5.0-py3-none-any.whl (15 kB)
03-Dec-2025 11:10:37 Collecting jaraco.functools
03-Dec-2025 11:10:37   Downloading jaraco_functools-4.3.0-py3-none-any.whl (10 kB)
03-Dec-2025 11:10:37 Collecting docutils>=0.21.2
03-Dec-2025 11:10:37   Downloading docutils-0.22.3-py3-none-any.whl (633 kB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 633.0/633.0 kB 71.1 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting nh3>=0.2.14
03-Dec-2025 11:10:37   Downloading nh3-0.3.2-cp38-abi3-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (797 kB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 797.2/797.2 kB 143.0 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting Pygments>=2.5.1
03-Dec-2025 11:10:37   Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 147.7 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting certifi>=2017.4.17
03-Dec-2025 11:10:37   Downloading certifi-2025.11.12-py3-none-any.whl (159 kB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 159.4/159.4 kB 45.9 MB/s eta 0:00:00
03-Dec-2025 11:10:37 Collecting idna<4,>=2.5
03-Dec-2025 11:10:37   Downloading idna-3.11-py3-none-any.whl (71 kB)
03-Dec-2025 11:10:37      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 71.0/71.0 kB 27.8 MB/s eta 0:00:00
03-Dec-2025 11:10:38 Collecting charset_normalizer<4,>=2
03-Dec-2025 11:10:38   Downloading charset_normalizer-3.4.4-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (153 kB)
03-Dec-2025 11:10:38      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 153.6/153.6 kB 554.0 kB/s eta 0:00:00
03-Dec-2025 11:10:38 Collecting markdown-it-py>=2.2.0
03-Dec-2025 11:10:38   Downloading markdown_it_py-4.0.0-py3-none-any.whl (87 kB)
03-Dec-2025 11:10:38      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 87.3/87.3 kB 35.9 MB/s eta 0:00:00
03-Dec-2025 11:10:38 Collecting zipp>=3.20
03-Dec-2025 11:10:38   Downloading zipp-3.23.0-py3-none-any.whl (10 kB)
03-Dec-2025 11:10:38 Collecting mdurl~=0.1
03-Dec-2025 11:10:38   Downloading mdurl-0.1.2-py3-none-any.whl (10.0 kB)
03-Dec-2025 11:10:38 Collecting cryptography>=2.0
03-Dec-2025 11:10:38   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_28_x86_64.whl (4.5 MB)
03-Dec-2025 11:10:38      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 181.5 MB/s eta 0:00:00
03-Dec-2025 11:10:38 Collecting more-itertools
03-Dec-2025 11:10:38   Downloading more_itertools-10.8.0-py3-none-any.whl (69 kB)
03-Dec-2025 11:10:38      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 69.7/69.7 kB 26.0 MB/s eta 0:00:00
03-Dec-2025 11:10:38 Collecting backports.tarfile
03-Dec-2025 11:10:38   Downloading backports.tarfile-1.2.0-py3-none-any.whl (30 kB)
03-Dec-2025 11:10:38 Collecting typing-extensions>=4.13.2
03-Dec-2025 11:10:38   Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB)
03-Dec-2025 11:10:38      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 20.8 MB/s eta 0:00:00
03-Dec-2025 11:10:39 Collecting cffi>=2.0.0
03-Dec-2025 11:10:39   Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB)
03-Dec-2025 11:10:39      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 216.5/216.5 kB 56.2 MB/s eta 0:00:00
03-Dec-2025 11:10:39 Collecting pycparser
03-Dec-2025 11:10:39   Downloading pycparser-2.23-py3-none-any.whl (118 kB)
03-Dec-2025 11:10:39      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 118.1/118.1 kB 60.2 MB/s eta 0:00:00
03-Dec-2025 11:10:39 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
03-Dec-2025 11:10:40 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
03-Dec-2025 11:10:40 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
03-Dec-2025 11:10:40
03-Dec-2025 11:10:40 [notice] A new release of pip is available: 23.0.1 -> 25.3
03-Dec-2025 11:10:40 [notice] To update, run: pip3 install --upgrade pip
03-Dec-2025 11:10:41 Cloning into 'casa-build-utils'...
03-Dec-2025 11:10:41 Uploading distributions to
03-Dec-2025 11:10:41 https://casa-pip.nrao.edu:443/repository/casa-dev-wheel/
03-Dec-2025 11:10:41 Uploading casatools-6.7.3.2a14681.dev3-cp312-cp312-manylinux_2_28_x86_64.whl
03-Dec-2025 11:10:41
03-Dec-2025 11:10:41   0% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 0.0/78.6 MB--:--?
03-Dec-2025 11:10:41 13% ━━━━━╺━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 10.4/78.6 MB00:01185.2 MB/s
03-Dec-2025 11:10:42 15% ━━━━━━╺━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 12.0/78.6 MB00:0187.4 MB/s
03-Dec-2025 11:10:42 19% ━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 14.8/78.6 MB00:0167.1 MB/s
03-Dec-2025 11:10:42 21% ━━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 16.9/78.6 MB00:0243.8 MB/s
03-Dec-2025 11:10:42 27% ━━━━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 21.3/78.6 MB00:0236.7 MB/s
03-Dec-2025 11:10:42 29% ━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 22.9/78.6 MB00:0232.1 MB/s
03-Dec-2025 11:10:42 33% ━━━━━━━━━━━━━╺━━━━━━━━━━━━━━━━━━━━━━━━━━ 25.7/78.6 MB00:0228.5 MB/s
03-Dec-2025 11:10:42 38% ━━━━━━━━━━━━━━━╺━━━━━━━━━━━━━━━━━━━━━━━━ 29.9/78.6 MB00:0228.9 MB/s
03-Dec-2025 11:10:42 40% ━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━━━━━━━━━━━━ 31.7/78.6 MB00:0229.1 MB/s
03-Dec-2025 11:10:42 44% ━━━━━━━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━━━━━━ 34.4/78.6 MB00:0229.2 MB/s
03-Dec-2025 11:10:42 46% ━━━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━━━━━━━━━━ 36.0/78.6 MB00:0229.3 MB/s
03-Dec-2025 11:10:43 51% ━━━━━━━━━━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━━━ 40.3/78.6 MB00:0229.3 MB/s
03-Dec-2025 11:10:43 55% ━━━━━━━━━━━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━━ 43.0/78.6 MB00:0229.4 MB/s
03-Dec-2025 11:10:43 57% ━━━━━━━━━━━━━━━━━━━━━━╸━━━━━━━━━━━━━━━━━ 44.9/78.6 MB00:0228.9 MB/s
03-Dec-2025 11:10:43 61% ━━━━━━━━━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━━━━ 47.7/78.6 MB00:0228.8 MB/s
03-Dec-2025 11:10:43 66% ━━━━━━━━━━━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━━ 51.7/78.6 MB00:0128.9 MB/s
03-Dec-2025 11:10:43 68% ━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━━━━━━━━━━━━ 53.4/78.6 MB00:0128.9 MB/s
03-Dec-2025 11:10:43 72% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━╸━━━━━━━━━━━ 56.3/78.6 MB00:0128.8 MB/s
03-Dec-2025 11:10:43 77% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╸━━━━━━━━━ 60.6/78.6 MB00:0129.6 MB/s
03-Dec-2025 11:10:43 79% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╸━━━━━━━━ 62.2/78.6 MB00:0129.7 MB/s
03-Dec-2025 11:10:43 81% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╸━━━━━━━ 63.9/78.6 MB00:0126.5 MB/s
03-Dec-2025 11:10:44 87% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╸━━━━━ 68.3/78.6 MB00:0130.0 MB/s
03-Dec-2025 11:10:44 91% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━━━ 71.2/78.6 MB00:0129.7 MB/s
03-Dec-2025 11:10:44 93% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━━ 72.9/78.6 MB00:0129.7 MB/s
03-Dec-2025 11:10:44 96% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╺━ 75.6/78.6 MB00:0129.2 MB/s
03-Dec-2025 11:10:44 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╸ 78.2/78.6 MB00:0128.2 MB/s
03-Dec-2025 11:10:44 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0224.9 MB/s
03-Dec-2025 11:10:44 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0224.9 MB/s
03-Dec-2025 11:10:44 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0224.9 MB/s
03-Dec-2025 11:10:44 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0224.9 MB/s
03-Dec-2025 11:10:44 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 78.6/78.6 MB00:0224.9 MB/s
03-Dec-2025 11:10:45 Uploading distributions to
03-Dec-2025 11:10:45 https://casa-pip.nrao.edu:443/repository/casa-dev-wheel/
03-Dec-2025 11:10:45 Uploading casatestutils-6.7.3.2a14681.dev3-py3-none-any.whl
03-Dec-2025 11:10:45
03-Dec-2025 11:10:45   0% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 0.0/149.8 kB--:--?
03-Dec-2025 11:10:45 100% ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 149.8/149.8 kB00:00181.3 MB/s
03-Dec-2025 11:10:45
03-Dec-2025 11:10:45 Finished task 'Upload wheel' with result: Success
03-Dec-2025 11:10:45
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:45
Substituting variable: ${bamboo.agentId} with 153550850
03-Dec-2025 11:10:45 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
03-Dec-2025 11:10:45
Beginning to execute external process for build 'CASASPECS - Dev Test 3.12 - CAS-14681 - Test Tools Many Linux 2.28 Py3.12 #3 (CASASPECS-DEVTESTPY31244-TTOMLPY312-3)'
... running command line:
/opt/ci_workspace/casa/bamboo/agent2/temp/CASASPECS-DEVTESTPY31244-TTOMLPY312-3-ScriptBuildTask-13115600059433485845.sh
... in: /opt/ci_workspace/casa/bamboo/agent2/xml-data/build-dir/CASASPECS-DEVTESTPY31244-TTOMLPY312
03-Dec-2025 11:10:45 Starting cleanup
03-Dec-2025 11:10:57 wheel-container-test_153550850
03-Dec-2025 11:10:59 wheel-container-test_153550850
03-Dec-2025 11:10:59 Cleanup complete
03-Dec-2025 11:10:59 Finished task 'Cleanup' with result: Success
03-Dec-2025 11:10:59 Running post build plugin 'Docker Container Cleanup'
03-Dec-2025 11:10:59
Beginning to execute external process for build 'CASASPECS - Dev Test 3.12 - CAS-14681 - Test Tools Many Linux 2.28 Py3.12 #3 (CASASPECS-DEVTESTPY31244-TTOMLPY312-3)'
... running command line:
/usr/bin/docker rm -f wheel-container-test_153550850
... in: /opt/ci_workspace/casa/bamboo/agent2/xml-data/build-dir/CASASPECS-DEVTESTPY31244-TTOMLPY312
03-Dec-2025 11:10:59 Error response from daemon: No such container: wheel-container-test_153550850
03-Dec-2025 11:10:59 Running post build plugin 'NCover Results Collector'
03-Dec-2025 11:10:59 Running post build plugin 'Build Results Label Collector'
03-Dec-2025 11:10:59 Running post build plugin 'Clover Results Collector'
03-Dec-2025 11:10:59 Running post build plugin 'npm Cache Cleanup'
03-Dec-2025 11:10:59 Running post build plugin 'Artifact Copier'
03-Dec-2025 11:10:59 Publishing an artifact: Workdir
03-Dec-2025 11:10:59 Finished publishing of artifact Non required job artifact Http Compression On : [Workdir], patterns: [work*.tar.gz] anchored at: [wheeldirectory/] in 64.12 ms
03-Dec-2025 11:10:59 Publishing an artifact: XML Directories
03-Dec-2025 11:10:59 Finished publishing of artifact Non required job artifact Http Compression On : [XML Directories], patterns: [**/*.xml] anchored at: [wheeldirectory/] in 471.2 ms
03-Dec-2025 11:10:59 Finalising the build...
03-Dec-2025 11:10:59 Stopping timer.
03-Dec-2025 11:10:59 Build CASASPECS-DEVTESTPY31244-TTOMLPY312-3 completed.
03-Dec-2025 11:10:59 Running on server: post build plugin 'Build Hanging Detection Configuration'
03-Dec-2025 11:10:59 Running on server: post build plugin 'NCover Results Collector'
03-Dec-2025 11:10:59 Running on server: post build plugin 'Build Labeller'
03-Dec-2025 11:10:59 Running on server: post build plugin 'Clover Delta Calculator'
03-Dec-2025 11:10:59 Running on server: post build plugin 'Maven Dependencies Postprocessor'
03-Dec-2025 11:10:59 All post build plugins have finished
03-Dec-2025 11:10:59 Generating build results summary...
03-Dec-2025 11:11:00 Saving build results to disk...
03-Dec-2025 11:11:00 Store variable context...
03-Dec-2025 11:11:00 Finished building CASASPECS-DEVTESTPY31244-TTOMLPY312-3.