Tools and Tasks tests for Python 3.12

Build: #6 failed

Job: Test Tasks Many Linux 2.28 Py3.12 did not complete

Build log

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

18-Oct-2024 13:32:17 PASSED
18-Oct-2024 13:32:17
18-Oct-2024 13:32:21 test_task_tclean.py::test_cube::test_cube_20 2024-10-18 17:32:22        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C5:P0] [C6:P0] [C7:P0] [C8:P0] [C9:P0]
18-Oct-2024 13:32:33
18-Oct-2024 13:32:33 [cube] Test_Cube_20   
18-Oct-2024 13:32:33 :  doppler, start={'m0': {'unit': 'm/s', 'value': 11994336.49363042}, 'refer': 'RADIO', 'type': 'doppler'}, default width, LSRK
18-Oct-2024 13:32:33 [check_spec_frame] tstCubetest_stdopdefwd.image: Spec frame is LSRK with crval 1199989152.1620603 (Pass : should be LSRK with expected crval 1199989152.0 )
18-Oct-2024 13:32:33 PASSED
18-Oct-2024 13:32:33
18-Oct-2024 13:32:37 test_task_tclean.py::test_cube::test_cube_21 2024-10-18 17:32:38        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C6:P0] [C7:P0]
18-Oct-2024 13:32:50
18-Oct-2024 13:32:50 [cube] Test_Cube_21   
18-Oct-2024 13:32:50 :  channel, start=4, default width, channel gap (10-11) LSRK
18-Oct-2024 13:32:50 [check_spec_frame] tstCubetest_st4gap.image: Spec frame is LSRK with crval 1199986500.4647074 (Pass : should be LSRK with expected crval 1199986500.0 )
18-Oct-2024 13:32:50 PASSED
18-Oct-2024 13:32:50
18-Oct-2024 13:33:07 test_task_tclean.py::test_cube::test_cube_22
18-Oct-2024 13:33:07 [cube] Test_Cube_22   
18-Oct-2024 13:33:07 :  channel, start=0, default width, step=2 LSRK nearest
18-Oct-2024 13:33:07 [check_spec_frame] tstCubetest_st4stride2.image: Spec frame is LSRK with crval 999988750.3872563 (Pass : should be LSRK with expected crval 999988750.387 )
18-Oct-2024 13:33:07 PASSED
18-Oct-2024 13:33:07
18-Oct-2024 13:33:24 test_task_tclean.py::test_cube::test_cube_23
18-Oct-2024 13:33:24 [cube] Test_Cube_23   
18-Oct-2024 13:33:24 :  spw with channel selection( 0:4~13 ), default start, LSRK nearest
18-Oct-2024 13:33:24 [check_spec_frame] tstCubetest_defstspwchansel4.image: Spec frame is TOPO with crval 1200000000.0 (Pass : should be TOPO with expected crval 1200000000.0 )
18-Oct-2024 13:33:24 PASSED
18-Oct-2024 13:33:24
18-Oct-2024 13:33:41 test_task_tclean.py::test_cube::test_cube_3
18-Oct-2024 13:33:41 [cube] Test_Cube_3   
18-Oct-2024 13:33:41 :  channel, start=5, default width, LSRK
18-Oct-2024 13:33:41 [check_spec_frame] tstCubetest_chanst5wd1.image: Spec frame is LSRK with crval 1249985937.9840703 (Pass : should be LSRK with expected crval 1249985937.0 )
18-Oct-2024 13:33:41 PASSED
18-Oct-2024 13:33:41
18-Oct-2024 13:33:45 test_task_tclean.py::test_cube::test_cube_4 2024-10-18 17:33:45        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C0:P0] [C1:P0] [C2:P0] [C3:P0] [C4:P0] [C5:P0]
18-Oct-2024 13:33:59
18-Oct-2024 13:33:59 [cube] Test_Cube_4   
18-Oct-2024 13:33:59 :  channel, spw=0:5~19, LSRK
18-Oct-2024 13:33:59 PASSED
18-Oct-2024 13:33:59
18-Oct-2024 13:34:16 test_task_tclean.py::test_cube::test_cube_5
18-Oct-2024 13:34:16 [cube] Test_Cube_5   
18-Oct-2024 13:34:16 :  frequency, default start, width='100MHz'(2 x chanwidth), TOPO
18-Oct-2024 13:34:16 [check_spec_frame] tstCubetest_freqdefstwd2.image: Spec frame is TOPO with crval 1025000000.0 (Pass : should be TOPO with expected crval 1025000000.0 )
18-Oct-2024 13:34:16 PASSED
18-Oct-2024 13:34:16
18-Oct-2024 13:34:33 test_task_tclean.py::test_cube::test_cube_6
18-Oct-2024 13:34:33 [cube] Test_Cube_6   
18-Oct-2024 13:34:33 :  frequency, start='1.1GHz', default width, TOPO
18-Oct-2024 13:34:33 [check_spec_frame] tstCubetest_freqst2defwd.image: Spec frame is TOPO with crval 1100000000.0 (Pass : should be TOPO with expected crval 1100000000.0 )
18-Oct-2024 13:34:33 PASSED
18-Oct-2024 13:34:33
18-Oct-2024 13:34:37 test_task_tclean.py::test_cube::test_cube_7 2024-10-18 17:34:37        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C0:P0] [C1:P0]
18-Oct-2024 13:34:49
18-Oct-2024 13:34:49 [cube] Test_Cube_7   
18-Oct-2024 13:34:49 :  frequency, start='1.1GHz', default width, spw=0:4~19, TOPO
18-Oct-2024 13:34:49 [check_spec_frame] tstCubetest_freqst2defwdspwsel.image: Spec frame is TOPO with crval 1100000000.0 (Pass : should be TOPO with expected crval 1100000000.0 )
18-Oct-2024 13:34:49 PASSED
18-Oct-2024 13:34:49
18-Oct-2024 13:35:06 test_task_tclean.py::test_cube::test_cube_8
18-Oct-2024 13:35:06 [cube] Test_Cube_8   
18-Oct-2024 13:35:06 :  frequency, start='1.5GHz', width='-50MHz', TOPO
18-Oct-2024 13:35:06 [check_spec_frame] tstCubetest_freqst10wdm.image: Spec frame is TOPO with crval 1500000000.0 (Pass : should be TOPO with expected crval 1500000000.0 )
18-Oct-2024 13:35:06 PASSED
18-Oct-2024 13:35:06
18-Oct-2024 13:35:24 test_task_tclean.py::test_cube::test_cube_9
18-Oct-2024 13:35:24 [cube] Test_Cube_9   
18-Oct-2024 13:35:24 :  frequency, default start, width='23983.4km/s', TOPO
18-Oct-2024 13:35:24 [check_spec_frame] tstCubetest_veldefstwd2.image: Spec frame is TOPO with crval 1925000133.092074 (Pass : should be TOPO with expected crval 1925000000.0 )
18-Oct-2024 13:35:24 PASSED
18-Oct-2024 13:35:24
18-Oct-2024 13:35:49 test_task_tclean.py::test_cube::test_cube_D1
18-Oct-2024 13:35:49 [cube] Test_Cube_D1 : specmode cubedata - No runtime doppler corrections 
18-Oct-2024 13:35:49 PASSED
18-Oct-2024 13:35:49
18-Oct-2024 13:36:09 test_task_tclean.py::test_cube::test_cube_D2
18-Oct-2024 13:36:09 [cube] Test_Cube_D2 : specmode cube - WITH doppler corrections 
18-Oct-2024 13:36:09 PASSED
18-Oct-2024 13:36:09
18-Oct-2024 13:36:10 test_task_tclean.py::test_cube::test_cube_badchannel_restoringbeam 2024-10-18 17:36:10        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:36:31
18-Oct-2024 13:36:31 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:36:39 2024-10-18 17:36:40        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:37:09
18-Oct-2024 13:37:09 [cube] Test auto restoring beam with a bad edge channel 
18-Oct-2024 13:37:09 PASSED
18-Oct-2024 13:37:09
18-Oct-2024 13:37:10 test_task_tclean.py::test_cube::test_cube_chanchunks_auto 2024-10-18 17:37:11        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:37:32
18-Oct-2024 13:37:32 [cube] Test channel chunking for large cubes : automatic calc of nchanchunks 
18-Oct-2024 13:37:32 PASSED
18-Oct-2024 13:37:32
18-Oct-2024 13:37:34 test_task_tclean.py::test_cube::test_cube_chanchunks_savemodel 2024-10-18 17:37:34        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:37:47
18-Oct-2024 13:38:20 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:38:30
18-Oct-2024 13:38:58 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:39:08
18-Oct-2024 13:39:08 [cube] Test channel chunking for large cubes and save model 
18-Oct-2024 13:39:08 PASSED
18-Oct-2024 13:39:08
18-Oct-2024 13:39:09 test_task_tclean.py::test_cube::test_cube_common_restoringbeam 2024-10-18 17:39:09        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:39:25 2024-10-18 17:39:26        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:39:37
18-Oct-2024 13:39:37 [cube] Test_cube_restoringbeam (cas10849/10946) : Test parallel and serial run on same refconcat images   
18-Oct-2024 13:39:37 PASSED
18-Oct-2024 13:39:37
18-Oct-2024 13:39:37 test_task_tclean.py::test_cube::test_cube_continuum_subtract_uvsub
18-Oct-2024 13:39:37 [cube] Test_Cube_continuum_subtract :  Using uvsub 
18-Oct-2024 13:39:37 SKIPPED (Skip until we can check why the model column is empty in al...)
18-Oct-2024 13:39:37
18-Oct-2024 13:39:58 test_task_tclean.py::test_cube::test_cube_descendF1
18-Oct-2024 13:39:58 [cube] Test_Cube_DescendF1: specmode cube with descending frequency data, default start and width   
18-Oct-2024 13:39:58 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1949978063.2551498 with cdelt -49999437.51936245 (Pass : should be LSRK with expected crval 1949978000.0 with expected cdelt -49999438.0 )
18-Oct-2024 13:39:58 PASSED
18-Oct-2024 13:39:58
18-Oct-2024 13:40:15 test_task_tclean.py::test_cube::test_cube_descendF10
18-Oct-2024 13:40:15 [cube] Test_Cube_DescendF10: specmode cube with descendign frequency data, start in velocity with defualt width 
18-Oct-2024 13:40:15 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1699980874.7690377 with cdelt -49999437.51936245 (Pass : should be LSRK with expected crval 1699980875.0 with expected cdelt -49999438.0 )
18-Oct-2024 13:40:15 PASSED
18-Oct-2024 13:40:15
18-Oct-2024 13:40:33 test_task_tclean.py::test_cube::test_cube_descendF11
18-Oct-2024 13:40:33 [cube] Test_Cube_DescendF11: specmode cube with descendign frequency data, start in velocity with defualt width 
18-Oct-2024 13:40:33 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1699980874.7690377 with cdelt -49999437.51920533 (Pass : should be LSRK with expected crval 1699980875.0 with expected cdelt -49999438.0 )
18-Oct-2024 13:40:33 PASSED
18-Oct-2024 13:40:33
18-Oct-2024 13:40:50 test_task_tclean.py::test_cube::test_cube_descendF12
18-Oct-2024 13:40:50 [cube] Test_Cube_DescendF12: specmode cube with descendign frequency data, start in velocity with defualt width 
18-Oct-2024 13:40:50 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1499983123.3246036 with cdelt 49999437.51920509 (Pass : should be LSRK with expected crval 1499983125.58 with expected cdelt 49999438.0 )
18-Oct-2024 13:40:50 PASSED
18-Oct-2024 13:40:50
18-Oct-2024 13:40:54 test_task_tclean.py::test_cube::test_cube_descendF13 2024-10-18 17:40:54        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C9:P0]
18-Oct-2024 13:41:06
18-Oct-2024 13:41:06 [cube] Test_Cube_DescendF13: specmode cube with descendign frequency data, start in velocity with defualt width 
18-Oct-2024 13:41:06 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1449983688.060026 with cdelt -49999437.51920533 (Pass : should be LSRK with expected crval 1449983688.0 with expected cdelt -49999437.5194 )
18-Oct-2024 13:41:06 PASSED
18-Oct-2024 13:41:06
18-Oct-2024 13:41:10 test_task_tclean.py::test_cube::test_cube_descendF14 2024-10-18 17:41:11        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C0:P0]
18-Oct-2024 13:41:23
18-Oct-2024 13:41:23 [cube] Test_Cube_DescendF14: specmode cube with descendign frequency data, start in velocity with defualt width 
18-Oct-2024 13:41:23 [check_spec_frame] tst.image: Spec frame is LSRK with crval 999988750.3871776 with cdelt 49999437.51920533 (Pass : should be LSRK with expected crval 999988750.387 with expected cdelt 49999438.0 )
18-Oct-2024 13:41:23 PASSED
18-Oct-2024 13:41:23
18-Oct-2024 13:41:39 test_task_tclean.py::test_cube::test_cube_descendF2
18-Oct-2024 13:41:39 [cube] Test_Cube_DescendF2: specmode cube with descending frequency data, start in channel no. with default width   
18-Oct-2024 13:41:39 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1699980875.6583362 with cdelt -49999437.51936293 (Pass : should be LSRK with expected crval 1699981000.0 with expected cdelt -49999438.0 )
18-Oct-2024 13:41:39 PASSED
18-Oct-2024 13:41:39
18-Oct-2024 13:41:56 test_task_tclean.py::test_cube::test_cube_descendF3
18-Oct-2024 13:41:56 [cube] Test_Cube_DescendF3: specmode cube with descending frequency data, start in channel no. with  width=1   
18-Oct-2024 13:41:56 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1699980875.6583362 with cdelt -49999437.51936293 (Pass : should be LSRK with expected crval 1699981000.0 with expected cdelt -49999438.0 )
18-Oct-2024 13:41:56 PASSED
18-Oct-2024 13:41:56
18-Oct-2024 13:42:13 test_task_tclean.py::test_cube::test_cube_descendF4
18-Oct-2024 13:42:13 [cube] Test_Cube_DescendF4: specmode cube with descending frequency data, start in freuquency  with  default width 
18-Oct-2024 13:42:13 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1499983125.5808845 with cdelt 49999437.51936293 (Pass : should be LSRK with expected crval 1499983125.0 with expected cdelt 49999438.0 )
18-Oct-2024 13:42:13 PASSED
18-Oct-2024 13:42:13
18-Oct-2024 13:42:30 test_task_tclean.py::test_cube::test_cube_descendF5
18-Oct-2024 13:42:30 [cube] Test_Cube_DescendF5: specmode cube with descending frequency data, start in freuquency  with  default width 
18-Oct-2024 13:42:30 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1499983124.9999995 with cdelt 49999437.51936293 (Pass : should be LSRK with expected crval 1499983125.0 with expected cdelt 49999438.0 )
18-Oct-2024 13:42:30 PASSED
18-Oct-2024 13:42:30
18-Oct-2024 13:42:47 test_task_tclean.py::test_cube::test_cube_descendF6
18-Oct-2024 13:42:47 [cube] Test_Cube_DescendF6: specmode cube with descending frequency data, start in freuquency  with  a positive width 
18-Oct-2024 13:42:47 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1499983124.9999998 with cdelt 49999438.0 (Pass : should be LSRK with expected crval 1499983125.0 with expected cdelt 49999438.0 )
18-Oct-2024 13:42:47 PASSED
18-Oct-2024 13:42:47
18-Oct-2024 13:43:04 test_task_tclean.py::test_cube::test_cube_descendF7
18-Oct-2024 13:43:04 [cube] Test_Cube_DescendF7: specmode cube with descending frequency data, start in freuquency  with  a negative width 
18-Oct-2024 13:43:04 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1699980999.9999998 with cdelt -49999438.0 (Pass : should be LSRK with expected crval 1699981000.0 with expected cdelt -49999438.0 )
18-Oct-2024 13:43:04 PASSED
18-Oct-2024 13:43:04
18-Oct-2024 13:43:21 test_task_tclean.py::test_cube::test_cube_descendF8
18-Oct-2024 13:43:21 [cube] Test_Cube_DescendF8: specmode cube with descending frequency data, width in  a positive freuquency  with  default start 
18-Oct-2024 13:43:21 [check_spec_frame] tst.image: Spec frame is LSRK with crval 999988750.6275749 with cdelt 49999438.0 (Pass : should be LSRK with expected crval 999989000.0 with expected cdelt 49999438.0 )
18-Oct-2024 13:43:21 PASSED
18-Oct-2024 13:43:21
18-Oct-2024 13:43:38 test_task_tclean.py::test_cube::test_cube_descendF9
18-Oct-2024 13:43:38 [cube] Test_Cube_DescendF9: specmode cube with descending frequency data, width in  a positive freuquency  with  default start 
18-Oct-2024 13:43:38 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1449983692.627575 with cdelt -49999438.0 (Pass : should be LSRK with expected crval 1449983692.63 with expected cdelt -49999438.0 )
18-Oct-2024 13:43:38 PASSED
18-Oct-2024 13:43:38
18-Oct-2024 13:43:51 test_task_tclean.py::test_cube::test_cube_flagged_mosaic_clark
18-Oct-2024 13:44:06 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:44:07 2024-10-18 17:44:07        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C0:P0]
18-Oct-2024 13:44:07 2024-10-18 17:44:08        WARN        tclean::::casa        Warning! Non-zero values at the edge of the .pb image can cause unexpected aliasing effects! (found value 1.0 at index [np.int64(0), np.int64(0), np.int64(0), np.int64(1)])
18-Oct-2024 13:44:18
18-Oct-2024 13:44:33 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:44:45
18-Oct-2024 13:45:17 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:45:28
18-Oct-2024 13:46:01 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:46:04
18-Oct-2024 13:46:04 CAS-12957: 0-value channels aren't skipped with gridder=mosaic and initial channels are flagged
18-Oct-2024 13:46:04 PASSED
18-Oct-2024 13:46:04
18-Oct-2024 13:46:17 test_task_tclean.py::test_cube::test_cube_flagged_mosaic_hogbom
18-Oct-2024 13:46:32 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:46:32 2024-10-18 17:46:33        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C0:P0]
18-Oct-2024 13:46:33 2024-10-18 17:46:34        WARN        tclean::::casa        Warning! Non-zero values at the edge of the .pb image can cause unexpected aliasing effects! (found value 1.0 at index [np.int64(0), np.int64(0), np.int64(0), np.int64(1)])
18-Oct-2024 13:46:43
18-Oct-2024 13:46:59 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:47:10
18-Oct-2024 13:47:43 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:47:54
18-Oct-2024 13:48:27 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:48:30
18-Oct-2024 13:48:30 CAS-12957: 0-value channels aren't skipped with gridder=mosaic and initial channels are flagged
18-Oct-2024 13:48:30 [check_tfmask]  T/F mask name for tst.image is : ['mask0'] (Pass : should be ['mask0'])
18-Oct-2024 13:48:30
18-Oct-2024 13:48:30 PASSED
18-Oct-2024 13:48:30
18-Oct-2024 13:48:30 test_task_tclean.py::test_cube::test_cube_flagged_mosaic_mtmfs
18-Oct-2024 13:48:30 CAS-12957: 0-value channels aren't skipped with gridder=mosaic and initial channels are flagged
18-Oct-2024 13:48:30 SKIPPED (Skip test.)
18-Oct-2024 13:48:30
18-Oct-2024 13:48:43 test_task_tclean.py::test_cube::test_cube_flagged_mosaic_multiscale
18-Oct-2024 13:48:59 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:48:59 2024-10-18 17:48:59        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C0:P0]
18-Oct-2024 13:49:00 2024-10-18 17:49:00        WARN        tclean::::casa        Warning! Non-zero values at the edge of the .pb image can cause unexpected aliasing effects! (found value 1.0 at index [np.int64(0), np.int64(0), np.int64(0), np.int64(1)])
18-Oct-2024 13:49:10
18-Oct-2024 13:49:27 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:49:39
18-Oct-2024 13:50:12 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:50:23
18-Oct-2024 13:50:55 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 13:50:59
18-Oct-2024 13:50:59 CAS-12957: 0-value channels aren't skipped with gridder=mosaic and initial channels are flagged
18-Oct-2024 13:50:59 PASSED
18-Oct-2024 13:50:59
18-Oct-2024 13:50:59 test_task_tclean.py::test_cube::test_cube_mtmfs_nterms1
18-Oct-2024 13:50:59 [cube] Test mtmfs with cube and nterms = 1 
18-Oct-2024 13:50:59 SKIPPED (Skip. This test deprecated. no longer need mtmfs for cube u...)
18-Oct-2024 13:50:59
18-Oct-2024 13:51:00 test_task_tclean.py::test_cube::test_cube_perchanweight_briggs 2024-10-18 17:51:00        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:51:23 2024-10-18 17:51:24        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:51:54 2024-10-18 17:51:54        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:52:21 2024-10-18 17:52:21        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:52:49
18-Oct-2024 13:52:49 [cube] test_cube_perchanweight_briggs: 
18-Oct-2024 13:52:49 PASSED
18-Oct-2024 13:52:49
18-Oct-2024 13:53:32 test_task_tclean.py::test_cube::test_cube_twoMS_startfreq
18-Oct-2024 13:53:32 [cube] Test cube with list of two MSs with start in frequency specified (test CAS-12877 fix) 
18-Oct-2024 13:53:32 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1199989000.0 (Pass : should be LSRK with expected crval 1199989000.0 )
18-Oct-2024 13:53:32 [check_spec_frame] tst_reverse.image: Spec frame is LSRK with crval 1199989000.0 (Pass : should be LSRK with expected crval 1199989000.0 )
18-Oct-2024 13:53:32 PASSED
18-Oct-2024 13:53:32
18-Oct-2024 13:54:18 test_task_tclean.py::test_cube::test_cube_twoMS_startvel
18-Oct-2024 13:54:18 [cube] Test cube with list of two MSs with start in velocity specified (test CAS-12877 fix) 
18-Oct-2024 13:54:18 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1199989152.16206 (Pass : should be LSRK with expected crval 1199989000.0 )
18-Oct-2024 13:54:18 [check_spec_frame] tst_reverse.image: Spec frame is LSRK with crval 1199989152.16206 (Pass : should be LSRK with expected crval 1199989000.0 )
18-Oct-2024 13:54:18 PASSED
18-Oct-2024 13:54:18
18-Oct-2024 13:54:19 test_task_tclean.py::test_cube::test_cube_weighting_taper 2024-10-18 17:54:20        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:54:50 2024-10-18 17:54:50        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:55:25 2024-10-18 17:55:25        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:55:52 2024-10-18 17:55:52        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:56:23
18-Oct-2024 13:56:23 [cube] test_cube_weighting_taper: 
18-Oct-2024 13:56:23 PASSED
18-Oct-2024 13:56:23
18-Oct-2024 13:56:24 test_task_tclean.py::test_cube::test_cube_weighting_taper_cas13260 2024-10-18 17:56:24        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:56:50 2024-10-18 17:56:51        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:57:21 2024-10-18 17:57:22        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:57:48 2024-10-18 17:57:49        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:58:15
18-Oct-2024 13:58:15 [cube] test_cube_weighting_taper_cas13260: 
18-Oct-2024 13:58:15 PASSED
18-Oct-2024 13:58:15
18-Oct-2024 13:58:15 test_task_tclean.py::test_cube::test_cube_weighting_taper_cas13260_old
18-Oct-2024 13:58:15 [cube] test_cube_weighting_taper_cas1326_old: 
18-Oct-2024 13:58:15 SKIPPED (Skip until we can figure out what causes the common bean di...)
18-Oct-2024 13:58:15
18-Oct-2024 13:58:37 test_task_tclean.py::test_cube::test_cubedata_briggs
18-Oct-2024 13:58:37 [cube] test_cubedata_briggs : specmode cubedata with perchanweightdensity- No runtime doppler corrections 
18-Oct-2024 13:58:37 PASSED
18-Oct-2024 13:58:37
18-Oct-2024 13:58:37 test_task_tclean.py::test_cube::test_cubedata_mtmfs_nterms1
18-Oct-2024 13:58:37 [cube] Test mtmfs with cube data and nterms = 1 
18-Oct-2024 13:58:37 SKIPPED (Skip. This test deprecated. no longer need mtmfs for cube u...)
18-Oct-2024 13:58:37
18-Oct-2024 13:59:10 test_task_tclean.py::test_mask::test_mask_1
18-Oct-2024 13:59:10 [mask] test_mask_1 : Input mask as file and string : mfs 
18-Oct-2024 13:59:10 PASSED
18-Oct-2024 13:59:10
18-Oct-2024 13:59:10 test_task_tclean.py::test_mask::test_mask_2 2024-10-18 17:59:11        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 13:59:35 2024-10-18 17:59:36        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:00:01
18-Oct-2024 14:00:01 [mask] test_mask_2 :  Input mask as file and string : cube (few channels) 
18-Oct-2024 14:00:01 PASSED
18-Oct-2024 14:00:01
18-Oct-2024 14:00:41 test_task_tclean.py::test_mask::test_mask_3
18-Oct-2024 14:00:41 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:00:47
18-Oct-2024 14:00:47 [mask] test_mask_3 : Input mask as image-to-be-regridded (ra/dec) : mfs 
18-Oct-2024 14:00:47 PASSED
18-Oct-2024 14:00:47
18-Oct-2024 14:00:47 test_task_tclean.py::test_mask::test_mask_4 2024-10-18 18:00:48        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:01:13 2024-10-18 18:01:13        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:01:16 2024-10-18 18:01:17        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C13:P0]
18-Oct-2024 14:01:26 2024-10-18 18:01:26        WARN        ImageRegridder::_regrid        Warning: template/imagename relative channel size is 1
18-Oct-2024 14:01:26 2024-10-18 18:01:27        WARN        ImageRegridder::_regrid        imregrid/ia.regrid() interpolates over spectral channels and does not average channels together. Noise in your resulting image will be the noise in the original individual channels, not the averaged channel noise. To average output channels together, use specsmooth (or ia.boxcar() or ia.hanning() to smooth the spectral axis of your input cube to close to desired resolution and use imregrid/ia.regrid() to regrid it to the desired spectral coordinate grid.
18-Oct-2024 14:01:37
18-Oct-2024 14:01:37 [mask] test_mask_4 :  Input mask as image-to-be-regridded(ra/dec/specframe) : cube 
18-Oct-2024 14:01:37 PASSED
18-Oct-2024 14:01:37
18-Oct-2024 14:01:38 test_task_tclean.py::test_mask::test_mask_5 2024-10-18 18:01:38        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:01:55 2024-10-18 18:01:55        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:02:02 2024-10-18 18:02:03        WARN        ImageRegridder::_regrid        Warning: template/imagename relative channel size is 1
18-Oct-2024 14:02:02 2024-10-18 18:02:03        WARN        ImageRegridder::_regrid        imregrid/ia.regrid() interpolates over spectral channels and does not average channels together. Noise in your resulting image will be the noise in the original individual channels, not the averaged channel noise. To average output channels together, use specsmooth (or ia.boxcar() or ia.hanning() to smooth the spectral axis of your input cube to close to desired resolution and use imregrid/ia.regrid() to regrid it to the desired spectral coordinate grid.
18-Oct-2024 14:02:15
18-Oct-2024 14:02:15 [mask] test_mask_5 : Input cube mask that has different chan
18-Oct-2024 14:02:15 ranges (use mask from the 1st tclean with a different channel range in the 2nd tclean run)
18-Oct-2024 14:02:15 PASSED
18-Oct-2024 14:02:15
18-Oct-2024 14:02:28 test_task_tclean.py::test_mask::test_mask_autobox_multithresh
18-Oct-2024 14:02:28 [mask] test_mask__autobox_multithresh :  multi-threshold Autobox (default)
18-Oct-2024 14:02:28 PASSED
18-Oct-2024 14:02:28
18-Oct-2024 14:02:41 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_cube_IQUV
18-Oct-2024 14:02:41 [mask] test_mask__autobox_multithresh_cube_IQUV :  multi-threshold Autobox (minbeamfrac=0.05) with cube full polarizaiton (IQUV) imaging 
18-Oct-2024 14:02:41 PASSED
18-Oct-2024 14:02:41
18-Oct-2024 14:02:52 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_mfs_IQUV
18-Oct-2024 14:02:52 [mask] test_mask__autobox_multithresh_mtmfs_IQUV :  multi-threshold Autobox (minbeamfrac=0.3) with cube full polarizaiton (IQUV) imaging 
18-Oct-2024 14:02:52 PASSED
18-Oct-2024 14:02:52
18-Oct-2024 14:03:06 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_newnoise
18-Oct-2024 14:03:06 [mask] test_mask__autobox_multithresh_newnoise :  multi-threshold Autobox invoking the new noise calc.
18-Oct-2024 14:03:06 PASSED
18-Oct-2024 14:03:06
18-Oct-2024 14:03:34 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_absorption
18-Oct-2024 14:03:34 [mask] test_mask__autobox_multithresh_on_absorption :  multi-threshold Autobox (minbeamfrac=0.3) on the data with both emission and absorption   
18-Oct-2024 14:03:34 PASSED
18-Oct-2024 14:03:34
18-Oct-2024 14:03:48 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_nsigma
18-Oct-2024 14:03:48 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:03:54
18-Oct-2024 14:03:54 [mask] test_mask__autobox_multithresh :  multi-threshold Autobox invoking the new noise calc.
18-Oct-2024 14:03:54 PASSED
18-Oct-2024 14:03:54
18-Oct-2024 14:04:06 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_nsigma_newnoise
18-Oct-2024 14:04:06 [mask] test_mask__autobox_multithresh :  multi-threshold Autobox invoking the new noise calc.
18-Oct-2024 14:04:06 PASSED
18-Oct-2024 14:04:06
18-Oct-2024 14:04:21 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_prune
18-Oct-2024 14:04:21 [mask] test_mask__autobox_multithresh_with_prune :  multi-threshold Autobox (minbeamfrac=0.3)
18-Oct-2024 14:04:21 PASSED
18-Oct-2024 14:04:21
18-Oct-2024 14:04:39 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_stopmask
18-Oct-2024 14:04:39 [mask] test_mask__autobox_multithresh_with_stopmask :  multi-threshold Autobox (minbeamfrac=0.3) with stop mask on 
18-Oct-2024 14:04:39 PASSED
18-Oct-2024 14:04:39
18-Oct-2024 14:04:39 test_task_tclean.py::test_mask::test_mask_combined_1
18-Oct-2024 14:04:39 [mask] test_mask_combined_1 :  string + pbmask 
18-Oct-2024 14:04:39 SKIPPED (Skip test.)
18-Oct-2024 14:04:39
18-Oct-2024 14:04:39 test_task_tclean.py::test_mask::test_mask_combined_2
18-Oct-2024 14:04:39 [mask] test_mask_combined_2 :  Autobox + pbmask 
18-Oct-2024 14:04:39 SKIPPED (Skip test.)
18-Oct-2024 14:04:39
18-Oct-2024 14:04:50 test_task_tclean.py::test_mask::test_mask_expand_contstokesIQUVmask_nodegen_to_cube_IQUV
18-Oct-2024 14:04:50 [mask] test_mask_expand_contstokesIQUVmask_nodegen_to_cube_IQUV : Test for expanding
18-Oct-2024 14:04:50 input continuum Stokes IQUV mask with its degenerate axes removed to cube IQUV imaging   
18-Oct-2024 14:04:50 PASSED
18-Oct-2024 14:04:50
18-Oct-2024 14:05:03 test_task_tclean.py::test_mask::test_mask_expand_contstokesIQUVmask_to_cube_IQUV
18-Oct-2024 14:05:03 [mask] test_mask_expand_contstokesIQUVmask_to_cube_IQUV : Test for expanding
18-Oct-2024 14:05:03 input continuum Stokes IQUV mask to cube IQUV imaging   
18-Oct-2024 14:05:03 PASSED
18-Oct-2024 14:05:03
18-Oct-2024 14:05:12 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_nodegen_to_IQUV
18-Oct-2024 14:05:12 [mask] test_mask_expand_contstokesImask_nodegen_to_IQUV : Test for expanding
18-Oct-2024 14:05:12 input continuum Stokes I mask with its degenerate axes removed to continuum multi-stokes imaging   
18-Oct-2024 14:05:12 PASSED
18-Oct-2024 14:05:12
18-Oct-2024 14:05:21 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_nodegen_to_cube
18-Oct-2024 14:05:21 [mask] test_mask_expand_contstokesImask_nodegen_to_cube : Test for
18-Oct-2024 14:05:21 expanding input continuum Stokes I mask with its degenerate axes removed to cube imaging   
18-Oct-2024 14:05:21 PASSED
18-Oct-2024 14:05:21
18-Oct-2024 14:05:32 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_nodegen_to_cube_IQUV
18-Oct-2024 14:05:32 [mask] test_mask_extend_contstokesImask_nodegen_to_cube_IQUV : Test for extending
18-Oct-2024 14:05:32 input continuum Stokes I mask with its denenerate axes removed to cube multi-stokes imaging   
18-Oct-2024 14:05:32 PASSED
18-Oct-2024 14:05:32
18-Oct-2024 14:05:41 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_to_IQUV
18-Oct-2024 14:05:41 [mask] test_mask_expand_contstokesImask_to_IQUV : Test for expanding
18-Oct-2024 14:05:41 input continuum Stokes I mask to continuum multi-stokes imaging   
18-Oct-2024 14:05:41 PASSED
18-Oct-2024 14:05:41
18-Oct-2024 14:05:48 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_to_cube
18-Oct-2024 14:05:48 [mask] test_mask_expand_contstokesImask_to_cube : Test for
18-Oct-2024 14:05:48 expanding input continuum Stokes I mask to cube imaging   
18-Oct-2024 14:05:48 PASSED
18-Oct-2024 14:05:48
18-Oct-2024 14:05:59 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_to_cube_IQUV
18-Oct-2024 14:05:59 [mask] test_mask_extend_contstokesImask_to_cube_IQUV : Test for extending
18-Oct-2024 14:05:59 input continuum Stokes I mask to cube multi-stokes imaging   
18-Oct-2024 14:05:59 PASSED
18-Oct-2024 14:05:59
18-Oct-2024 14:06:10 test_task_tclean.py::test_mask::test_mask_expand_cubestokesImask_nodegen_to_cube_IQUV
18-Oct-2024 14:06:10 [mask] test_mask_expand_contstokesIQUVmask_nodegen_to_cube_IQUV : Test for expanding
18-Oct-2024 14:06:10 input cube Stokes I mask with its degenerate axes removed to cube (of the same spectral coordinates)  IQUV imaging   
18-Oct-2024 14:06:10 PASSED
18-Oct-2024 14:06:10
18-Oct-2024 14:06:21 test_task_tclean.py::test_mask::test_mask_expand_cubestokesImask_to_cube_IQUV
18-Oct-2024 14:06:21 [mask] test_mask_expand_contstokesIQUVmask_to_cube_IQUV : Test for expanding
18-Oct-2024 14:06:21 input cube Stokes I mask to cube (of the same spectral coordinates)  IQUV imaging   
18-Oct-2024 14:06:21 PASSED
18-Oct-2024 14:06:21
18-Oct-2024 14:06:22 test_task_tclean.py::test_mask::test_mask_long_region_specification 2024-10-18 18:06:22        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:06:37 2024-10-18 18:06:37        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
18-Oct-2024 14:06:50
18-Oct-2024 14:06:50 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:07:04
18-Oct-2024 14:07:04 [mask] test_mask_long_region_specification : Test the fix for CAS-13624   
18-Oct-2024 14:07:04 report= [ check_ret_structure ] Return dictionary struture check: type=reduced ( Pass : found all expected keys  )
18-Oct-2024 14:07:04 [ check_ims ] Image made : ['tst.mask', 'tst.image'] =  [True, True] ( Pass : should all be True )
18-Oct-2024 14:07:04 All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
18-Oct-2024 14:07:04 [check_history] All expected history entries found. (Pass)
18-Oct-2024 14:07:04 [ check_pix_val ] tst.mask : Value is 1.0 at [256, 256, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
18-Oct-2024 14:07:04 [ check_pix_val ] tst.mask : Value is 1.0 at [255, 253, 0, 3] ( Pass : should be 1.0 , Epsilon: 0.05)
18-Oct-2024 14:07:04 [ check_pix_val ] tst.mask : Value is 0.0 at [260, 259, 0, 0] ( Pass : should be 0.0 , Epsilon: 0.05)
18-Oct-2024 14:07:04 [ check_logs ]: found 1 matching log lines (Pass)
18-Oct-2024 14:07:04
18-Oct-2024 14:07:04 PASSED
18-Oct-2024 14:07:04
18-Oct-2024 14:07:04 test_task_tclean.py::test_mask::test_mask_outlier
18-Oct-2024 14:07:04 [mask] test_mask_outlier : With outlier fields 
18-Oct-2024 14:07:04 SKIPPED (Skip test.)
18-Oct-2024 14:07:04
18-Oct-2024 14:07:04 test_task_tclean.py::test_mask::test_mask_pbmask
18-Oct-2024 14:07:04 [mask] test_mask_pbmask :  pb mask 
18-Oct-2024 14:07:04 SKIPPED (Skip test.)
18-Oct-2024 14:07:04
18-Oct-2024 14:07:22 test_task_tclean.py::test_mask::test_mask_preserve_input_zero_mask 2024-10-18 18:07:22        WARN        SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:07:24
18-Oct-2024 14:07:24 Test the fix for CAS-14203; If a user explicitly provides a
18-Oct-2024 14:07:24 zero-filled input mask, it should be respected and not flipped.
18-Oct-2024 14:07:24 PASSED
18-Oct-2024 14:07:24
18-Oct-2024 14:07:32 test_task_tclean.py::test_mask::test_mask_zerostart 2024-10-18 18:07:33        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:07:45 2024-10-18 18:07:46        WARN        SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:07:48
18-Oct-2024 14:07:48 [mask] test_mask_zerostart : Test that a zero starting mask is caught   
18-Oct-2024 14:07:48 len(summ_minor)= 1
18-Oct-2024 14:07:48 [check_stopcode] Stopcode is 7 (Pass : should be 7)
18-Oct-2024 14:07:48
18-Oct-2024 14:07:48 PASSED
18-Oct-2024 14:07:48
18-Oct-2024 14:08:03 test_task_tclean.py::test_wproject::test_wterm_awproject
18-Oct-2024 14:11:30 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:11:30
18-Oct-2024 14:11:30 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:11:30 2024-10-18 18:11:31        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
18-Oct-2024 14:11:30 2024-10-18 18:11:31        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:11:58 2024-10-18 18:11:59        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:12:00
18-Oct-2024 14:12:00 [wproject] Test_Widefield_wproj : W-Projection using the AWProject gridder 
18-Oct-2024 14:12:00 PASSED
18-Oct-2024 14:12:00
18-Oct-2024 14:12:23 test_task_tclean.py::test_wproject::test_wterm_facets 2024-10-18 18:12:24        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:12:26
18-Oct-2024 14:12:26 [wproject] Test_Widefield_wproj : Facets 
18-Oct-2024 14:12:26 PASSED
18-Oct-2024 14:12:26
18-Oct-2024 14:12:43 test_task_tclean.py::test_wproject::test_wterm_wproject 2024-10-18 18:12:44        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:12:45
18-Oct-2024 14:12:45 [wproject] Test_Widefield_wproj : W-Projection 
18-Oct-2024 14:12:45 [check_tfmask]  T/F mask name for tst.wyes.image is : ['T'] (Pass : should be ['T'])
18-Oct-2024 14:12:45
18-Oct-2024 14:12:45 PASSED
18-Oct-2024 14:12:45
18-Oct-2024 14:13:07 test_task_tclean.py::test_wproject::test_wterm_wproject_facets 2024-10-18 18:13:08        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:13:09
18-Oct-2024 14:13:09 [wproject] Test_Widefield_wproj : Facets with wprojection per facet
18-Oct-2024 14:13:09 PASSED
18-Oct-2024 14:13:09
18-Oct-2024 14:13:26 test_task_tclean.py::test_widefield::test_mosaicft_newpsfphasecenter
18-Oct-2024 14:13:26 test_mosaicft_newpsfphasecenter : different phasecenter for psf
18-Oct-2024 14:13:26 PASSED
18-Oct-2024 14:13:26
18-Oct-2024 14:14:00 test_task_tclean.py::test_widefield::test_mosaicft_newpsfphasecenter_cube
18-Oct-2024 14:14:00 test_mosaicft_newpsfphasecenter_cube : different phasecenter for psf
18-Oct-2024 14:14:00 PASSED
18-Oct-2024 14:14:00
18-Oct-2024 14:14:01 test_task_tclean.py::test_widefield::test_widefield_aproj_cube 2024-10-18 18:14:02        WARN        SynthesisImagerVi2::dryGridding (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 3056)        Making a "blank" CFCache
18-Oct-2024 14:14:02 2024-10-18 18:14:02        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
18-Oct-2024 14:14:02 2024-10-18 18:14:02        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:14:05 2024-10-18 18:14:06        WARN        SynthesisImagerVi2::fillCFCache (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 3140)        Re-loading the "blank" CFCache for filling
18-Oct-2024 14:14:05 2024-10-18 18:14:06        WARN        crustaceans::makeConvFunction2[R&D]        Filling CFs for baseline type 0, PA slot 0
18-Oct-2024 14:14:05 Processing request for geometries from observatory EVLA, antenna type STANDARD
18-Oct-2024 14:16:23 2024-10-18 18:16:24        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
18-Oct-2024 14:16:23 2024-10-18 18:16:24        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:16:35
18-Oct-2024 14:16:35 [widefield] Test_Widefield_aproj_cube_aproj : Cube with AW-Projection  and rotation off 
18-Oct-2024 14:16:35 PASSED
18-Oct-2024 14:16:35
18-Oct-2024 14:17:19 test_task_tclean.py::test_widefield::test_widefield_aproj_mfs
18-Oct-2024 14:17:19 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:17:19
18-Oct-2024 14:17:19 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:17:20 2024-10-18 18:17:20        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
18-Oct-2024 14:17:20 2024-10-18 18:17:20        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:17:31
18-Oct-2024 14:17:36 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:17:46
18-Oct-2024 14:17:52 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:17:56
18-Oct-2024 14:17:56 [widefield] Test_Widefield_aproj : MFS with narrowband AWProjection (wbawp=F, 1spw)  stokes I 
18-Oct-2024 14:17:56 PASSED
18-Oct-2024 14:17:56
18-Oct-2024 14:18:41 test_task_tclean.py::test_widefield::test_widefield_aproj_mtmfs
18-Oct-2024 14:20:07 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:20:07
18-Oct-2024 14:20:07 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:20:07 2024-10-18 18:20:07        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 0 during gridding to compute sensitivity pattern.
18-Oct-2024 14:20:07 2024-10-18 18:20:07        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:20:10 2024-10-18 18:20:10        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 1 during gridding to compute sensitivity pattern.
18-Oct-2024 14:20:10 2024-10-18 18:20:10        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:20:10 2024-10-18 18:20:10        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 2 during gridding to compute sensitivity pattern.
18-Oct-2024 14:20:10 2024-10-18 18:20:10        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:20:17
18-Oct-2024 14:20:17 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:20:24 2024-10-18 18:20:24        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:20:24 2024-10-18 18:20:25        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:20:30
18-Oct-2024 14:20:30 [widefield] Test_Widefield_aproj_mtmfs : MFS with AWProjection (wbawp=T,conjbeams=F, allspw) and nt=2 stokes I   
18-Oct-2024 14:20:30 PASSED
18-Oct-2024 14:20:30
18-Oct-2024 14:20:48 test_task_tclean.py::test_widefield::test_widefield_mosaicft_cube
18-Oct-2024 14:20:48 [widefield] Test_Widefield_mosaicft_cube : MFS with mosaicft  stokes I 
18-Oct-2024 14:20:48 PASSED
18-Oct-2024 14:20:48
18-Oct-2024 14:21:02 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mfs
18-Oct-2024 14:21:02 [widefield] Test_Widefield_mosaic : MFS with mosaicft  stokes I 
18-Oct-2024 14:21:02 [check_tfmask]  T/F mask name for tst.image is : ['mask0'] (Pass : should be ['mask0'])
18-Oct-2024 14:21:02
18-Oct-2024 14:21:02 PASSED
18-Oct-2024 14:21:02
18-Oct-2024 14:21:26 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mfs_mosweightTrue
18-Oct-2024 14:21:34 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:21:37
18-Oct-2024 14:21:37 [widefield] Test_Widefield_mosaic : MFS with mosaicft  stokes I briggs mosweight=True(default)
18-Oct-2024 14:21:37 PASSED
18-Oct-2024 14:21:37
18-Oct-2024 14:21:49 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mtmfs
18-Oct-2024 14:21:49 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:22:05
18-Oct-2024 14:22:06 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:22:16
18-Oct-2024 14:22:16 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:22:26
18-Oct-2024 14:22:26 [widefield] Test_Widefield_mosaicft_mtmfs : MT-MFS with mosaicft  stokes I, alpha 
18-Oct-2024 14:22:26 [check_tfmask]  T/F mask name for tst.image.tt0 is : ['mask0'] (Pass : should be ['mask0'])
18-Oct-2024 14:22:26
18-Oct-2024 14:22:26 PASSED
18-Oct-2024 14:22:26
18-Oct-2024 14:22:38 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mtmfs_conj
18-Oct-2024 14:22:38 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:22:56
18-Oct-2024 14:22:56 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:23:04
18-Oct-2024 14:23:04 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:23:13
18-Oct-2024 14:23:13 [widefield] Test_Widefield_mosaicft_mtmfs : MT-MFS with mosaicft  stokes I, alpha 
18-Oct-2024 14:23:13 PASSED
18-Oct-2024 14:23:13
18-Oct-2024 14:23:21 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mtmfs_pbsquare
18-Oct-2024 14:23:21 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:23:42
18-Oct-2024 14:23:42 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:23:52
18-Oct-2024 14:23:52 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:24:02
18-Oct-2024 14:24:02 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:24:11
18-Oct-2024 14:24:11 [widefield] Test_Widefield_mosaicft_mtmfs : MT-MFS with mosaicft  stokes I, alpha 
18-Oct-2024 14:24:11 PASSED
18-Oct-2024 14:24:11
18-Oct-2024 14:24:56 test_task_tclean.py::test_widefield::test_widefield_wbaproj_mfs
18-Oct-2024 14:26:22 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:26:22
18-Oct-2024 14:26:22 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:26:22 2024-10-18 18:26:23        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
18-Oct-2024 14:26:22 2024-10-18 18:26:23        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:26:40
18-Oct-2024 14:26:40 [widefield] Test_Widefield_wbaproj_mfs : MFS with wideband AWProjection (wbawp=T, allspw) and nt=1 stokes I   
18-Oct-2024 14:26:40 PASSED
18-Oct-2024 14:26:40
18-Oct-2024 14:27:25 test_task_tclean.py::test_widefield::test_widefield_wbaproj_mtmfs
18-Oct-2024 14:28:50 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:28:50
18-Oct-2024 14:28:50 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:28:50 2024-10-18 18:28:50        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 0 during gridding to compute sensitivity pattern.
18-Oct-2024 14:28:50 2024-10-18 18:28:50        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:28:53 2024-10-18 18:28:53        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 1 during gridding to compute sensitivity pattern.
18-Oct-2024 14:28:53 2024-10-18 18:28:53        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:28:53 2024-10-18 18:28:53        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 2 during gridding to compute sensitivity pattern.
18-Oct-2024 14:28:53 2024-10-18 18:28:53        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
18-Oct-2024 14:29:00
18-Oct-2024 14:29:00 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:29:26
18-Oct-2024 14:29:26 [widefield] Test_Widefield_wbaproj_mtmfs : MFS with wideband AWProjection (wbawp=T,conjbeams=T, allspw) and nt=2 stokes I   
18-Oct-2024 14:29:26 PASSED
18-Oct-2024 14:29:26
18-Oct-2024 14:29:38 test_task_tclean.py::test_modelvis::test_modelvis_1
18-Oct-2024 14:29:38 [modelpredict] Test_modelvis_1 : mfs with no save model 
18-Oct-2024 14:29:38 PASSED
18-Oct-2024 14:29:38
18-Oct-2024 14:29:51 test_task_tclean.py::test_modelvis::test_modelvis_10
18-Oct-2024 14:30:22 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:30:32
18-Oct-2024 14:30:57 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:31:16
18-Oct-2024 14:31:44 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:32:02
18-Oct-2024 14:32:02 [modelpredict] Test_modelvis_10 : Use input model of different (narrower) freq range than data 
18-Oct-2024 14:32:02 [check_chanvals] Chan 3  is (0.9999999999999999+0j) (Pass : should be ==1.0)
18-Oct-2024 14:32:02
18-Oct-2024 14:32:02 [check_chanvals] Chan 3  is (0.6942970446700978+0j) (Pass : should be >0.0)
18-Oct-2024 14:32:02
18-Oct-2024 14:32:02 PASSED
18-Oct-2024 14:32:02
18-Oct-2024 14:32:16 test_task_tclean.py::test_modelvis::test_modelvis_11
18-Oct-2024 14:32:46 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:32:56
18-Oct-2024 14:33:23 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:33:42
18-Oct-2024 14:34:11 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:34:28
18-Oct-2024 14:34:28 [modelpredict] Test_modelvis_11 : Predict model image over channel gaps not included in imaging 
18-Oct-2024 14:34:28 PASSED
18-Oct-2024 14:34:28
18-Oct-2024 14:34:46 test_task_tclean.py::test_modelvis::test_modelvis_12
18-Oct-2024 14:35:09 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:35:11
18-Oct-2024 14:35:11 [modelpredict] Test_modelvis_12 : (CAS-12618) mfs with automask and save model column (single tclean call, internally a separate predit model step)
18-Oct-2024 14:35:11 PASSED
18-Oct-2024 14:35:11
18-Oct-2024 14:35:26 test_task_tclean.py::test_modelvis::test_modelvis_13
18-Oct-2024 14:35:26 [modelpredict] Test_modelvis_13 : (CAS-12618) mfs with automask and save virtual model (saving model via a separate predict model step)
18-Oct-2024 14:35:26 PASSED
18-Oct-2024 14:35:26
18-Oct-2024 14:35:44 test_task_tclean.py::test_modelvis::test_modelvis_14
18-Oct-2024 14:36:06 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:36:09
18-Oct-2024 14:36:09 [modelpredict] Test_modelvis_14 : (cas-12618) mfs with nsigma and save model column (saving model via a separate predict model step)
18-Oct-2024 14:36:09 PASSED
18-Oct-2024 14:36:09
18-Oct-2024 14:36:23 test_task_tclean.py::test_modelvis::test_modelvis_15
18-Oct-2024 14:36:23 [modelpredict] Test_modelvis_15 : (CAS-12618) mfs with nsigma and save model column (saving model via a separate predict model step)
18-Oct-2024 14:36:23 PASSED
18-Oct-2024 14:36:23
18-Oct-2024 14:36:24 test_task_tclean.py::test_modelvis::test_modelvis_16 2024-10-18 18:36:25        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:36:45 2024-10-18 18:36:46        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:36:53
18-Oct-2024 14:37:23 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:37:31
18-Oct-2024 14:37:31 [modelpredict] Test_modelvis_16 : (CAS-12618) cube with  and save  model column for auto-multithresh
18-Oct-2024 14:37:31 PASSED
18-Oct-2024 14:37:31
18-Oct-2024 14:37:32 test_task_tclean.py::test_modelvis::test_modelvis_17 2024-10-18 18:37:32        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:37:51 2024-10-18 18:37:51        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:38:02
18-Oct-2024 14:38:02 [modelpredict] Test_modelvis_17 : (CAS-12618) cube with  and save virtual model for auto-multithreseh
18-Oct-2024 14:38:02 PASSED
18-Oct-2024 14:38:02
18-Oct-2024 14:38:02 test_task_tclean.py::test_modelvis::test_modelvis_18 2024-10-18 18:38:03        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:38:18 2024-10-18 18:38:19        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:38:26
18-Oct-2024 14:38:56 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:39:05
18-Oct-2024 14:39:05 [modelpredict] Test_modelvis_18 : (CAS-12618) cube with  and save model column for nsgima >0.0
18-Oct-2024 14:39:05 PASSED
18-Oct-2024 14:39:05
18-Oct-2024 14:39:06 test_task_tclean.py::test_modelvis::test_modelvis_19 2024-10-18 18:39:06        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:39:21 2024-10-18 18:39:22        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:39:32
18-Oct-2024 14:39:32 [modelpredict] Test_modelvis_19 : (CAS-12618) cube with  and save virtual model for nsima >0.0
18-Oct-2024 14:39:32 PASSED
18-Oct-2024 14:39:32
18-Oct-2024 14:39:48 test_task_tclean.py::test_modelvis::test_modelvis_2
18-Oct-2024 14:40:11 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:40:20
18-Oct-2024 14:40:43 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:40:59
18-Oct-2024 14:41:23 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:41:25
18-Oct-2024 14:41:25 [modelpredict] Test_modelvis_2 : mfs with save model column 
18-Oct-2024 14:41:25 PASSED
18-Oct-2024 14:41:25
18-Oct-2024 14:41:46 test_task_tclean.py::test_modelvis::test_modelvis_20
18-Oct-2024 14:42:09 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:42:10
18-Oct-2024 14:42:10 [modelpredict] Test_modelvis_20 : (CAS-13615) mfs with automask and save model column (saving model via a separate niter=0 tclean call)
18-Oct-2024 14:42:10 PASSED
18-Oct-2024 14:42:10
18-Oct-2024 14:42:26 test_task_tclean.py::test_modelvis::test_modelvis_21
18-Oct-2024 14:42:26 [modelpredict] Test_modelvis_21 : (CAS-13615) mfs with automask and save virtual model (saving model via a separate niter=0 tclean call)
18-Oct-2024 14:42:26 PASSED
18-Oct-2024 14:42:26
18-Oct-2024 14:42:47 test_task_tclean.py::test_modelvis::test_modelvis_22
18-Oct-2024 14:43:09 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:43:10
18-Oct-2024 14:43:10 [modelpredict] Test_modelvis_22 : (cas-13615) mfs with nsigma and save model column (saving model via a separate niter=0 tclean call)
18-Oct-2024 14:43:10 PASSED
18-Oct-2024 14:43:10
18-Oct-2024 14:43:26 test_task_tclean.py::test_modelvis::test_modelvis_23
18-Oct-2024 14:43:26 [modelpredict] Test_modelvis_23 : (CAS-13615) mfs with nsigma and save model column (saving model via a separate niterpredict model step)
18-Oct-2024 14:43:26 PASSED
18-Oct-2024 14:43:26
18-Oct-2024 14:43:27 test_task_tclean.py::test_modelvis::test_modelvis_24 2024-10-18 18:43:28        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:43:54 2024-10-18 18:43:55        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:44:02
18-Oct-2024 14:44:31 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:44:34
18-Oct-2024 14:44:34 [modelpredict] Test_modelvis_24 : (CAS-13615) cube with  and save  model column for auto-multithresh (in two steps)
18-Oct-2024 14:44:34 PASSED
18-Oct-2024 14:44:34
18-Oct-2024 14:44:35 test_task_tclean.py::test_modelvis::test_modelvis_25 2024-10-18 18:44:36        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:45:01 2024-10-18 18:45:02        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:45:06
18-Oct-2024 14:45:06 [modelpredict] Test_modelvis_25: (CAS-13615) cube with and save virtual model for auto-multithreseh (in two steps) 
18-Oct-2024 14:45:06 PASSED
18-Oct-2024 14:45:06
18-Oct-2024 14:45:07 test_task_tclean.py::test_modelvis::test_modelvis_26 2024-10-18 18:45:07        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:45:31 2024-10-18 18:45:31        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:45:38
18-Oct-2024 14:46:08 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:46:10
18-Oct-2024 14:46:10 [modelpredict] Test_modelvis_26 : (CAS-13615) cube with and save model column for nsgima >0.0 (in two steps) 
18-Oct-2024 14:46:10 PASSED
18-Oct-2024 14:46:10
18-Oct-2024 14:46:11 test_task_tclean.py::test_modelvis::test_modelvis_27 2024-10-18 18:46:12        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:46:35 2024-10-18 18:46:35        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:46:39
18-Oct-2024 14:46:39 [modelpredict] Test_modelvis_27: (CAS-13615) cube with and save virtual model for nsigma >0.0 (in two steps) 
18-Oct-2024 14:46:39 PASSED
18-Oct-2024 14:46:39
18-Oct-2024 14:46:40 test_task_tclean.py::test_modelvis::test_modelvis_28 2024-10-18 18:46:40        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_tclean/refim_point_onespw0.ms/table.f19
18-Oct-2024 14:46:40 2024-10-18 18:46:40        WARN        tclean::::casa        Column WEIGHT_SPECTRUM in table refim_point_onespw0.ms has no data. Accessing it will cause errors.
18-Oct-2024 14:46:40 2024-10-18 18:46:40        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_tclean/refim_point_onespw1.ms/table.f19
18-Oct-2024 14:46:40 2024-10-18 18:46:40        WARN        tclean::::casa        Column WEIGHT_SPECTRUM in table refim_point_onespw1.ms has no data. Accessing it will cause errors.
18-Oct-2024 14:46:54
18-Oct-2024 14:46:54 [modelpredict] Test_modelvis_28: (CAS-13925) mfs with two MSes, savemodel=modelcolumn, nsigma > 0.0 
18-Oct-2024 14:46:54 PASSED
18-Oct-2024 14:46:54
18-Oct-2024 14:46:55 test_task_tclean.py::test_modelvis::test_modelvis_29 2024-10-18 18:46:56        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_tclean/refim_point_onespw0.ms/table.f19
18-Oct-2024 14:46:55 2024-10-18 18:46:56        WARN        tclean::::casa        Column WEIGHT_SPECTRUM in table refim_point_onespw0.ms has no data. Accessing it will cause errors.
18-Oct-2024 14:46:55 2024-10-18 18:46:56        SEVERE        getcell::WEIGHT_SPECTRUM        Exception Reported: Table DataManager error: Invalid operation: TSM: no array in row 0 of column WEIGHT_SPECTRUM in /home/casatest/casa6/casatestutils/nosedir/test_task_tclean/refim_point_onespw1.ms/table.f19
18-Oct-2024 14:46:55 2024-10-18 18:46:56        WARN        tclean::::casa        Column WEIGHT_SPECTRUM in table refim_point_onespw1.ms has no data. Accessing it will cause errors.
18-Oct-2024 14:47:11
18-Oct-2024 14:47:11 [modelpredict] Test_modelvis_29: (CAS-13925) cube with two MSes, savemodel=modelcolumn, nsigma > 0.0 
18-Oct-2024 14:47:11 PASSED
18-Oct-2024 14:47:11
18-Oct-2024 14:47:35 test_task_tclean.py::test_modelvis::test_modelvis_3
18-Oct-2024 14:47:35 [modelpredict] Test_modelvis_3 : mfs with save virtual model 
18-Oct-2024 14:47:35 PASSED
18-Oct-2024 14:47:35
18-Oct-2024 14:47:58 test_task_tclean.py::test_modelvis::test_modelvis_4
18-Oct-2024 14:47:58 [modelpredict] Test_modelvis_4 : mt-mfs with no save model 
18-Oct-2024 14:47:58 PASSED
18-Oct-2024 14:47:58
18-Oct-2024 14:48:14 test_task_tclean.py::test_modelvis::test_modelvis_5
18-Oct-2024 14:48:39 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:48:50
18-Oct-2024 14:49:13 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:49:37
18-Oct-2024 14:50:03 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:50:11
18-Oct-2024 14:50:11 [modelpredict] Test_modelvis_5 : mt-mfs with save model column 
18-Oct-2024 14:50:11 PASSED
18-Oct-2024 14:50:11
18-Oct-2024 14:50:55 test_task_tclean.py::test_modelvis::test_modelvis_6
18-Oct-2024 14:50:55 [modelpredict] Test_modelvis_6 : mt-mfs with save virtual model 
18-Oct-2024 14:50:55 PASSED
18-Oct-2024 14:50:55
18-Oct-2024 14:50:56 test_task_tclean.py::test_modelvis::test_modelvis_7 2024-10-18 18:50:57        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.425e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:51:08
18-Oct-2024 14:51:38 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:51:47
18-Oct-2024 14:52:12 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:52:20 2024-10-18 18:52:20        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:52:34
18-Oct-2024 14:53:03 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:53:13
18-Oct-2024 14:53:13 [modelpredict] Test_modelvis_7 : cube with chan selection and save model column 
18-Oct-2024 14:53:13 [check_chanvals] Chan 3  is (0.9999999999999999+0j) (Pass : should be ==1.0)
18-Oct-2024 14:53:13
18-Oct-2024 14:53:13 [check_chanvals] Chan 3  is (0.7863571717245512+0j) (Pass : should be ==(0.7863571722888284+0j))
18-Oct-2024 14:53:13
18-Oct-2024 14:53:13 PASSED
18-Oct-2024 14:53:13
18-Oct-2024 14:53:14 test_task_tclean.py::test_modelvis::test_modelvis_8 2024-10-18 18:53:15        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.425e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:53:17 2024-10-18 18:53:18        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C0:P0]
18-Oct-2024 14:53:31 2024-10-18 18:53:31        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:53:54
18-Oct-2024 14:53:54 [modelpredict] Test_modelvis_8 : cube with chan selection and save virtual model 
18-Oct-2024 14:53:54 PASSED
18-Oct-2024 14:53:54
18-Oct-2024 14:54:28 test_task_tclean.py::test_modelvis::test_modelvis_9
18-Oct-2024 14:54:56 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:55:12
18-Oct-2024 14:55:12 [modelpredict] Test_modelvis_9 : Don't de-grid channels with zero model. Also test limited-freq mask 
18-Oct-2024 14:55:13 PASSED
18-Oct-2024 14:55:13
18-Oct-2024 14:55:16 test_task_tclean.py::test_startmodel::test_csys_startmodel_restart_cube 2024-10-18 18:55:16        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:55:25 2024-10-18 18:55:26        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:55:29 2024-10-18 18:55:30        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:55:43 2024-10-18 18:55:44        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:55:53
18-Oct-2024 14:56:22 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:56:28 2024-10-18 18:56:29        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:56:38 2024-10-18 18:56:38        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 14:56:42 2024-10-18 18:56:42        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:56:56 2024-10-18 18:56:56        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 14:57:04
18-Oct-2024 14:57:29 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 [startmodel] test_csys_startmodel_restart_cube : Check that csys differences w.r.to latpoles for parallel vs serial runs are appropriately squashed.
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 Run a sequence of tclean runs to trigger a complicated situation of restarts, mixing serial/parallel and model writes.
18-Oct-2024 14:57:33 This sequence, coupled with the algorithm options listed below in tests #1 through #6 trigger three different errors that
18-Oct-2024 14:57:33 have been fixed in this branch, and one that will be addressed via CAS-9386 (cube refactor).
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 tclean call sequence :
18-Oct-2024 14:57:33 --- (a) Parallel run for niter=0
18-Oct-2024 14:57:33 --- (b) Serial/Parallel run for niter=10, with calcres=F, calcpsf=F  : to reuse images from prev.
18-Oct-2024 14:57:33 --- (c) Serial model-predict run (without/with startmodel) : in one case it reuses prev image-set. in other case it reuses only 'model'.
18-Oct-2024 14:57:33 --- (d) Impbcor on the output of (b)
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 Note that this is not a full fix of the various instances of the 'latpole' inconsistency, but only a workaround.
18-Oct-2024 14:57:33 Hence it needs a test to ensure this keeps working. 
18-Oct-2024 14:57:33 Run with cube - hogbom - False
18-Oct-2024 14:57:33 Run with cube - hogbom - usestartmodel = False - imaging in parallel
18-Oct-2024 14:57:33 [check_chanvals] Chan 19  is (0.5040750624302417+0j) (Pass : should be <0.9)
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 Run with cube - hogbom - True
18-Oct-2024 14:57:33 Run with cube - hogbom - usestartmodel = True - imaging in parallel
18-Oct-2024 14:57:33 [check_chanvals] Chan 19  is (0.5040750624302417+0j) (Pass : should be <0.9)
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 PASSED
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 test_task_tclean.py::test_startmodel::test_csys_startmodel_restart_mfs
18-Oct-2024 14:57:33 [startmodel] test_csys_startmodel_restart_cube
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 Run a sequence of tclean runs to trigger a complicated situation of restarts, mixing serial/parallel and model writes.
18-Oct-2024 14:57:33 This sequence, coupled with the algorithm options listed below in tests #1 through #6 trigger three different errors that
18-Oct-2024 14:57:33 have been fixed in this branch, and one that will be addressed via CAS-9386 (cube refactor).
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 tclean call sequence :
18-Oct-2024 14:57:33 --- (a) Parallel run for niter=0
18-Oct-2024 14:57:33 --- (b) Serial/Parallel run for niter=10, with calcres=F, calcpsf=F  : to reuse images from prev.
18-Oct-2024 14:57:33 --- (c) Serial model-predict run (without/with startmodel) : in one case it reuses prev image-set. in other case it reuses only 'model'.
18-Oct-2024 14:57:33 --- (d) Impbcor on the output of (b)
18-Oct-2024 14:57:33
18-Oct-2024 14:57:33 Note that this is not a full fix of the various instances of the 'latpole' inconsistency, but only a workaround.
18-Oct-2024 14:57:33 Hence it needs a test to ensure this keeps working. 
18-Oct-2024 14:57:33 SKIPPED (Skip the test temporarily)
18-Oct-2024 14:57:33
18-Oct-2024 14:57:45 test_task_tclean.py::test_startmodel::test_startmodel_mfs_changeshape_1 2024-10-18 18:57:46        SEVERE        tclean::::casa        Task tclean raised an exception of class RuntimeError with the following message: Error in making PSF : Cannot open existing image : tst1.psf : There is a shape mismatch between existing images ([100, 100, 1, 1]) and current parameters ([120, 120, 1, 1]). If you are attempting to restart a run with a new image shape, please change imagename and supply the old model or mask as inputs (via the startmodel or mask parameters) so that they can be regridded to the new shape before continuing.
18-Oct-2024 14:57:46
18-Oct-2024 14:57:46 [startmodel] test_startmodel_mfs_changeshape_1 : Restart a run but change shape only (cas-6937)
18-Oct-2024 14:57:46 PASSED
18-Oct-2024 14:57:46
18-Oct-2024 14:58:10 test_task_tclean.py::test_startmodel::test_startmodel_mfs_changeshape_2
18-Oct-2024 14:58:10 [startmodel] test_startmodel_mfs_changeshape_2 : Restart a run using 'startmodel' and change shape and imagename
18-Oct-2024 14:58:10 PASSED
18-Oct-2024 14:58:10
18-Oct-2024 14:58:31 test_task_tclean.py::test_startmodel::test_startmodel_mfs_continue
18-Oct-2024 14:58:31 [startmodel] test_startmodel_mfs_continue : Restart a run with no parameter changes
18-Oct-2024 14:58:31 PASSED
18-Oct-2024 14:58:31
18-Oct-2024 14:58:55 test_task_tclean.py::test_startmodel::test_startmodel_mfs_restart
18-Oct-2024 14:58:55 [startmodel] test_startmodel_mfs_restart : Restart a run using 'startmodel' and changed imagename
18-Oct-2024 14:58:55 PASSED
18-Oct-2024 14:58:55
18-Oct-2024 15:00:26 test_task_tclean.py::test_startmodel::test_startmodel_mtmfs_restart
18-Oct-2024 15:00:26 [startmodel] test_startmodel_mtmfs_restart : Restart a multi-term run using 'startmodel' and changed imagename
18-Oct-2024 15:00:26 PASSED
18-Oct-2024 15:00:26
18-Oct-2024 15:00:27 test_task_tclean.py::test_startmodel::test_startmodel_regrid_cube 2024-10-18 19:00:27        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 15:00:31 2024-10-18 19:00:32        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2075)        PSF is blank for[C18:P0] [C19:P0]
18-Oct-2024 15:00:49 2024-10-18 19:00:50        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2940)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
18-Oct-2024 15:01:09
18-Oct-2024 15:01:09 [modelpredict] Test_startmodel_regrid_cube : Regrid input model onto new image grid : cube (ra/dec/specframe)
18-Oct-2024 15:01:09 PASSED
18-Oct-2024 15:01:09
18-Oct-2024 15:01:24 test_task_tclean.py::test_startmodel::test_startmodel_regrid_mfs
18-Oct-2024 15:01:24 [modelpredict] Test_startmodel_regrid_mfs : Regrid input model onto new image grid : mfs (ra/dec) 
18-Oct-2024 15:01:24 PASSED
18-Oct-2024 15:01:24
18-Oct-2024 15:01:49 test_task_tclean.py::test_startmodel::test_startmodel_with_mask_mfs
18-Oct-2024 15:01:53 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 15:02:11
18-Oct-2024 15:02:11 [startmodel] test_startmodel_with_mask_mfs : Mask out some regions in the startmodel, before prediction 
18-Oct-2024 15:02:11 PASSED
18-Oct-2024 15:02:11
18-Oct-2024 15:02:52 test_task_tclean.py::test_startmodel::test_startmodel_with_mask_mtmfs
18-Oct-2024 15:02:57 0%....10....20....30....40....50....60....70....80....90....100%
18-Oct-2024 15:03:32
18-Oct-2024 15:03:32 [startmodel] test_startmodel_with_mask_mtmfs : Mask out some regions in the startmodel, before prediction 
18-Oct-2024 15:03:32 PASSED
18-Oct-2024 15:03:32
18-Oct-2024 15:03:45 test_task_tclean.py::test_pbcor::test_pbcor_cube_basic
18-Oct-2024 15:03:45 [pbcor] Test pbcor with cube
18-Oct-2024 15:03:45 PASSED
18-Oct-2024 15:03:45
18-Oct-2024 15:04:02 test_task_tclean.py::test_pbcor::test_pbcor_cube_mosaicft
18-Oct-2024 15:04:02 [pbcor] Test pbcor with cube with mosaicft
18-Oct-2024 15:04:02 [check_tfmask]  T/F mask name for tst.image is : ['T'] (Pass : should be ['T'])
18-Oct-2024 15:04:02 [check_tfmask]  T/F mask name for tst.pb is : ['mask0'] (Pass : should be ['mask0'])
18-Oct-2024 15:04:02 [check_tfmask]  T/F mask name for tst.image.pbcor is : ['mask0'] (Pass : should be ['mask0'])
18-Oct-2024 15:04:02
18-Oct-2024 15:04:02 PASSED
18-Oct-2024 15:04:02
18-Oct-2024 15:04:17 test_task_tclean.py::test_pbcor::test_pbcor_cube_twosteps
18-Oct-2024 15:04:17 [pbcor] Test pbcor with cube with imaging and pbcor separately
18-Oct-2024 15:04:17 PASSED
18-Oct-2024 15:04:17
18-Oct-2024 15:04:27 test_task_tclean.py::test_pbcor::test_pbcor_mfs
18-Oct-2024 15:04:27 [pbcor] Test pbcor with mfs
18-Oct-2024 15:04:27 PASSED
18-Oct-2024 15:04:27
18-Oct-2024 15:04:31 test_task_tclean.py::test_pbcor::test_pbcor_mfs_restart 2024-10-18 19:04:32        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 15:04:41
18-Oct-2024 15:04:41 [pbcor] Test pbcor with mfs and a restart
18-Oct-2024 15:04:41 PASSED
18-Oct-2024 15:04:41
18-Oct-2024 15:04:41 test_task_tclean.py::test_pbcor::test_pbcor_mtmfs
18-Oct-2024 15:04:41 [pbcor] Test pbcor with mtmfs
18-Oct-2024 15:04:41 SKIPPED (mfs+mtmfs+pbcor no longer recommended)
18-Oct-2024 15:04:41
18-Oct-2024 15:04:46 test_task_tclean.py::test_pbcor::test_pbcor_turn_off_pbmask 2024-10-18 19:04:46        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2284)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
18-Oct-2024 15:04:54
18-Oct-2024 15:04:54 [pbcor] Test pbcor with mfs where the internal T/F mask is turned off
18-Oct-2024 15:04:54 [check_tfmask]  T/F mask name for tst.image is : ['T'] (Pass : should be ['T'])
18-Oct-2024 15:04:54 [check_tfmask]  T/F mask name for tst.pb is : ['mask0'] (Pass : should be ['mask0'])
18-Oct-2024 15:04:54 [check_tfmask]  T/F mask name for tst.image.pbcor is : ['mask0'] (Pass : should be ['mask0'])
18-Oct-2024 15:04:54
18-Oct-2024 15:04:54 PASSED
18-Oct-2024 15:04:54
18-Oct-2024 15:08:38 Failing task since return code of [/export/home/cbt-el7-8/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTM228PY312-6-ScriptBuildTask-9127142863927215448.sh] was 143 while expected 0
18-Oct-2024 15:08:38 Finished task 'Test wheel' with result: Failed
18-Oct-2024 15:08:38 Starting task 'Cleanup' of type 'com.atlassian.bamboo.plugins.scripttask:task.builder.script'
18-Oct-2024 15:08:38
Beginning to execute external process for build 'CASASPECS - Master Test 3.12 - Test Tasks Many Linux 2.28 Py3.12 #6 (CASASPECS-MASTERTESTPY312-TTM228PY312-6)'
... running command line:
/export/home/cbt-el7-8/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTM228PY312-6-ScriptBuildTask-3775746061821648679.sh
... in: /export/home/cbt-el7-8/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTM228PY312
18-Oct-2024 15:08:38 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
18-Oct-2024 15:08:38 Attempting to generate stack trace and terminate spawned sub-processes of process id: 24371
18-Oct-2024 15:08:38 Found related process: pid: 4028 ppid: 24371 pgid: 1207 %cpu: 0.0 %mem: 0.0 cmd: /bin/sh
18-Oct-2024 15:08:38 Found related process: pid: 14332 ppid: 1 pgid: 1207 %cpu: 0.0 %mem: 0.0 cmd: docker
18-Oct-2024 15:08:38 getStackTraceAndKillRelatedProcesses for 2 processes
18-Oct-2024 15:08:38 Executing kill -3 14332
18-Oct-2024 15:08:38 Executing kill -3 4028
18-Oct-2024 15:08:39 test_task_tclean.py::test_hetarray_imaging::test_het_antenna_mosaic_cube_alma
18-Oct-2024 15:08:39 Starting cleanup
18-Oct-2024 15:08:44 Killing: 14332
18-Oct-2024 15:08:44 Executing kill 14332
18-Oct-2024 15:08:44 Killing: 4028
18-Oct-2024 15:08:44 Executing kill 4028
18-Oct-2024 15:08:49 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
18-Oct-2024 15:08:49 Has finished generating stack trace and terminating spawned sub-processes of process id: 24371
18-Oct-2024 15:08:49 Failing task since return code of [/export/home/cbt-el7-8/bamboohome/temp/CASASPECS-MASTERTESTPY312-TTM228PY312-6-ScriptBuildTask-3775746061821648679.sh] was 143 while expected 0
18-Oct-2024 15:08:49 Finished task 'Cleanup' with result: Failed
18-Oct-2024 15:08:49 Running post build plugin 'Docker Container Cleanup'
18-Oct-2024 15:08:49
Beginning to execute external process for build 'CASASPECS - Master Test 3.12 - Test Tasks Many Linux 2.28 Py3.12 #6 (CASASPECS-MASTERTESTPY312-TTM228PY312-6)'
... running command line:
/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-8/bamboohome/xml-data/build-dir/CASASPECS-MASTERTESTPY312-TTM228PY312
18-Oct-2024 15:08:56 wheel-container-test
18-Oct-2024 15:08:56 Running post build plugin 'NCover Results Collector'
18-Oct-2024 15:08:56 Running post build plugin 'Build Results Label Collector'
18-Oct-2024 15:08:56 Running post build plugin 'Clover Results Collector'
18-Oct-2024 15:08:56 Running post build plugin 'npm Cache Cleanup'
18-Oct-2024 15:08:56 Running post build plugin 'Artifact Copier'
18-Oct-2024 15:08:56 Publishing an artifact: Workdir
18-Oct-2024 15:08:56 Failing as no matching files has been found and empty artifacts are not allowed.
18-Oct-2024 15:08:56 Unable to publish artifact [Workdir]:
18-Oct-2024 15:08:56 The artifact hasn't been successfully published after 86.05 ms
18-Oct-2024 15:08:56 Publishing an artifact: Tests
18-Oct-2024 15:08:56 Failing as no matching files has been found and empty artifacts are not allowed.
18-Oct-2024 15:08:56 Unable to publish artifact [Tests]:
18-Oct-2024 15:08:56 The artifact hasn't been successfully published after 16.78 ms
18-Oct-2024 15:08:56 Publishing an artifact: xml Directories
18-Oct-2024 15:08:56 Failing as no matching files has been found and empty artifacts are not allowed.
18-Oct-2024 15:08:56 Unable to publish artifact [xml Directories]:
18-Oct-2024 15:08:56 The artifact hasn't been successfully published after 21.29 ms
18-Oct-2024 15:08:56 Finalising the build...
18-Oct-2024 15:08:56 Stopping timer.
18-Oct-2024 15:08:56 Build CASASPECS-MASTERTESTPY312-TTM228PY312-6 completed.
18-Oct-2024 15:08:56 Running on server: post build plugin 'Build Hanging Detection Configuration'
18-Oct-2024 15:08:56 Running on server: post build plugin 'NCover Results Collector'
18-Oct-2024 15:08:56 Running on server: post build plugin 'Build Labeller'
18-Oct-2024 15:08:56 Running on server: post build plugin 'Clover Delta Calculator'
18-Oct-2024 15:08:56 Running on server: post build plugin 'Maven Dependencies Postprocessor'
18-Oct-2024 15:08:56 All post build plugins have finished
18-Oct-2024 15:08:56 Generating build results summary...
18-Oct-2024 15:08:56 Saving build results to disk...
18-Oct-2024 15:08:56 Store variable context...
18-Oct-2024 15:08:56 Finished building CASASPECS-MASTERTESTPY312-TTM228PY312-6.