Tools and Tasks tests for Python 3.12

Build: #27 did not complete

Job: Test Tasks Many Linux 2.34 Rocky 9.5 Py3.12 did not complete

Build log

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

22-Jul-2025 08:22:26 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:22:35
22-Jul-2025 08:22:35 [cube] Test channel chunking for large cubes and save model 
22-Jul-2025 08:22:35 PASSED
22-Jul-2025 08:22:35
22-Jul-2025 08:22:35 test_task_tclean.py::test_cube::test_cube_common_restoringbeam 2025-07-22 12:22:36        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:22:51 2025-07-22 12:22:51        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:23:01
22-Jul-2025 08:23:01 [cube] Test_cube_restoringbeam (cas10849/10946) : Test parallel and serial run on same refconcat images   
22-Jul-2025 08:23:01 PASSED
22-Jul-2025 08:23:01
22-Jul-2025 08:23:01 test_task_tclean.py::test_cube::test_cube_continuum_subtract_uvsub
22-Jul-2025 08:23:01 [cube] Test_Cube_continuum_subtract :  Using uvsub 
22-Jul-2025 08:23:01 SKIPPED (Skip until we can check why the model column is empty in al...)
22-Jul-2025 08:23:01
22-Jul-2025 08:23:21 test_task_tclean.py::test_cube::test_cube_descendF1
22-Jul-2025 08:23:21 [cube] Test_Cube_DescendF1: specmode cube with descending frequency data, default start and width   
22-Jul-2025 08:23:21 [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 )
22-Jul-2025 08:23:21 PASSED
22-Jul-2025 08:23:21
22-Jul-2025 08:23:37 test_task_tclean.py::test_cube::test_cube_descendF10
22-Jul-2025 08:23:37 [cube] Test_Cube_DescendF10: specmode cube with descendign frequency data, start in velocity with defualt width 
22-Jul-2025 08:23:37 [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 )
22-Jul-2025 08:23:37 PASSED
22-Jul-2025 08:23:37
22-Jul-2025 08:23:56 test_task_tclean.py::test_cube::test_cube_descendF11
22-Jul-2025 08:23:56 [cube] Test_Cube_DescendF11: specmode cube with descendign frequency data, start in velocity with defualt width 
22-Jul-2025 08:23:56 [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 )
22-Jul-2025 08:23:56 PASSED
22-Jul-2025 08:23:56
22-Jul-2025 08:24:12 test_task_tclean.py::test_cube::test_cube_descendF12
22-Jul-2025 08:24:12 [cube] Test_Cube_DescendF12: specmode cube with descendign frequency data, start in velocity with defualt width 
22-Jul-2025 08:24:12 [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 )
22-Jul-2025 08:24:12 PASSED
22-Jul-2025 08:24:12
22-Jul-2025 08:24:17 test_task_tclean.py::test_cube::test_cube_descendF13 2025-07-22 12:24:18        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2136)        PSF is blank for[C9:P0]
22-Jul-2025 08:24:30
22-Jul-2025 08:24:30 [cube] Test_Cube_DescendF13: specmode cube with descendign frequency data, start in velocity with defualt width 
22-Jul-2025 08:24:30 [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 )
22-Jul-2025 08:24:30 PASSED
22-Jul-2025 08:24:30
22-Jul-2025 08:24:34 test_task_tclean.py::test_cube::test_cube_descendF14 2025-07-22 12:24:35        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2136)        PSF is blank for[C0:P0]
22-Jul-2025 08:24:53
22-Jul-2025 08:24:53 [cube] Test_Cube_DescendF14: specmode cube with descendign frequency data, start in velocity with defualt width 
22-Jul-2025 08:24:53 [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 )
22-Jul-2025 08:24:53 PASSED
22-Jul-2025 08:24:53
22-Jul-2025 08:25:10 test_task_tclean.py::test_cube::test_cube_descendF2
22-Jul-2025 08:25:10 [cube] Test_Cube_DescendF2: specmode cube with descending frequency data, start in channel no. with default width   
22-Jul-2025 08:25:10 [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 )
22-Jul-2025 08:25:10 PASSED
22-Jul-2025 08:25:10
22-Jul-2025 08:25:26 test_task_tclean.py::test_cube::test_cube_descendF3
22-Jul-2025 08:25:26 [cube] Test_Cube_DescendF3: specmode cube with descending frequency data, start in channel no. with  width=1   
22-Jul-2025 08:25:26 [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 )
22-Jul-2025 08:25:26 PASSED
22-Jul-2025 08:25:26
22-Jul-2025 08:25:42 test_task_tclean.py::test_cube::test_cube_descendF4
22-Jul-2025 08:25:42 [cube] Test_Cube_DescendF4: specmode cube with descending frequency data, start in freuquency  with  default width 
22-Jul-2025 08:25:42 [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 )
22-Jul-2025 08:25:42 PASSED
22-Jul-2025 08:25:42
22-Jul-2025 08:26:00 test_task_tclean.py::test_cube::test_cube_descendF5
22-Jul-2025 08:26:00 [cube] Test_Cube_DescendF5: specmode cube with descending frequency data, start in freuquency  with  default width 
22-Jul-2025 08:26:00 [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 )
22-Jul-2025 08:26:00 PASSED
22-Jul-2025 08:26:00
22-Jul-2025 08:26:17 test_task_tclean.py::test_cube::test_cube_descendF6
22-Jul-2025 08:26:17 [cube] Test_Cube_DescendF6: specmode cube with descending frequency data, start in freuquency  with  a positive width 
22-Jul-2025 08:26:17 [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 )
22-Jul-2025 08:26:17 PASSED
22-Jul-2025 08:26:17
22-Jul-2025 08:26:32 test_task_tclean.py::test_cube::test_cube_descendF7
22-Jul-2025 08:26:32 [cube] Test_Cube_DescendF7: specmode cube with descending frequency data, start in freuquency  with  a negative width 
22-Jul-2025 08:26:32 [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 )
22-Jul-2025 08:26:32 PASSED
22-Jul-2025 08:26:32
22-Jul-2025 08:26:47 test_task_tclean.py::test_cube::test_cube_descendF8
22-Jul-2025 08:26:47 [cube] Test_Cube_DescendF8: specmode cube with descending frequency data, width in  a positive freuquency  with  default start 
22-Jul-2025 08:26:47 [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 )
22-Jul-2025 08:26:47 PASSED
22-Jul-2025 08:26:47
22-Jul-2025 08:27:01 test_task_tclean.py::test_cube::test_cube_descendF9
22-Jul-2025 08:27:01 [cube] Test_Cube_DescendF9: specmode cube with descending frequency data, width in  a positive freuquency  with  default start 
22-Jul-2025 08:27:01 [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 )
22-Jul-2025 08:27:01 PASSED
22-Jul-2025 08:27:01
22-Jul-2025 08:27:15 test_task_tclean.py::test_cube::test_cube_flagged_mosaic_clark
22-Jul-2025 08:27:17 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:27:17 2025-07-22 12:27:17        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2136)        PSF is blank for[C0:P0]
22-Jul-2025 08:27:18 2025-07-22 12:27:18        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)])
22-Jul-2025 08:27:29
22-Jul-2025 08:27:31 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:27:43
22-Jul-2025 08:27:51 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:28:02
22-Jul-2025 08:28:10 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:28:14
22-Jul-2025 08:28:14 CAS-12957: 0-value channels aren't skipped with gridder=mosaic and initial channels are flagged
22-Jul-2025 08:28:14 PASSED
22-Jul-2025 08:28:14
22-Jul-2025 08:28:29 test_task_tclean.py::test_cube::test_cube_flagged_mosaic_hogbom
22-Jul-2025 08:28:30 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:28:30 2025-07-22 12:28:31        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2136)        PSF is blank for[C0:P0]
22-Jul-2025 08:28:31 2025-07-22 12:28:31        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)])
22-Jul-2025 08:28:41
22-Jul-2025 08:28:43 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:28:55
22-Jul-2025 08:29:02 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:29:13
22-Jul-2025 08:29:21 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:29:24
22-Jul-2025 08:29:24 CAS-12957: 0-value channels aren't skipped with gridder=mosaic and initial channels are flagged
22-Jul-2025 08:29:24 [check_tfmask]  T/F mask name for tst.image is : ['mask0'] (Pass : should be ['mask0'])
22-Jul-2025 08:29:24
22-Jul-2025 08:29:24 PASSED
22-Jul-2025 08:29:24
22-Jul-2025 08:29:24 test_task_tclean.py::test_cube::test_cube_flagged_mosaic_mtmfs
22-Jul-2025 08:29:24 CAS-12957: 0-value channels aren't skipped with gridder=mosaic and initial channels are flagged
22-Jul-2025 08:29:24 SKIPPED (Skip test.)
22-Jul-2025 08:29:24
22-Jul-2025 08:29:38 test_task_tclean.py::test_cube::test_cube_flagged_mosaic_multiscale
22-Jul-2025 08:29:39 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:29:39 2025-07-22 12:29:40        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2136)        PSF is blank for[C0:P0]
22-Jul-2025 08:29:40 2025-07-22 12:29:41        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)])
22-Jul-2025 08:29:51
22-Jul-2025 08:29:53 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:30:04
22-Jul-2025 08:30:11 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:30:22
22-Jul-2025 08:30:29 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:30:31
22-Jul-2025 08:30:31 CAS-12957: 0-value channels aren't skipped with gridder=mosaic and initial channels are flagged
22-Jul-2025 08:30:31 PASSED
22-Jul-2025 08:30:31
22-Jul-2025 08:30:31 test_task_tclean.py::test_cube::test_cube_mtmfs_nterms1
22-Jul-2025 08:30:31 [cube] Test mtmfs with cube and nterms = 1 
22-Jul-2025 08:30:31 SKIPPED (Skip. This test deprecated. no longer need mtmfs for cube u...)
22-Jul-2025 08:30:31
22-Jul-2025 08:30:32 test_task_tclean.py::test_cube::test_cube_perchanweight_briggs 2025-07-22 12:30:33        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:31:02 2025-07-22 12:31:03        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:31:30 2025-07-22 12:31:30        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:31:55 2025-07-22 12:31:56        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:32:19
22-Jul-2025 08:32:19 [cube] test_cube_perchanweight_briggs: 
22-Jul-2025 08:32:19 PASSED
22-Jul-2025 08:32:19
22-Jul-2025 08:33:03 test_task_tclean.py::test_cube::test_cube_twoMS_startfreq
22-Jul-2025 08:33:03 [cube] Test cube with list of two MSs with start in frequency specified (test CAS-12877 fix) 
22-Jul-2025 08:33:03 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1199989000.0 (Pass : should be LSRK with expected crval 1199989000.0 )
22-Jul-2025 08:33:03 [check_spec_frame] tst_reverse.image: Spec frame is LSRK with crval 1199989000.0 (Pass : should be LSRK with expected crval 1199989000.0 )
22-Jul-2025 08:33:03 PASSED
22-Jul-2025 08:33:03
22-Jul-2025 08:33:47 test_task_tclean.py::test_cube::test_cube_twoMS_startvel
22-Jul-2025 08:33:47 [cube] Test cube with list of two MSs with start in velocity specified (test CAS-12877 fix) 
22-Jul-2025 08:33:47 [check_spec_frame] tst.image: Spec frame is LSRK with crval 1199989152.16206 (Pass : should be LSRK with expected crval 1199989000.0 )
22-Jul-2025 08:33:47 [check_spec_frame] tst_reverse.image: Spec frame is LSRK with crval 1199989152.16206 (Pass : should be LSRK with expected crval 1199989000.0 )
22-Jul-2025 08:33:47 PASSED
22-Jul-2025 08:33:47
22-Jul-2025 08:33:48 test_task_tclean.py::test_cube::test_cube_weighting_taper 2025-07-22 12:33:48        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:34:26 2025-07-22 12:34:27        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:34:53 2025-07-22 12:34:54        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:35:17 2025-07-22 12:35:18        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:35:47
22-Jul-2025 08:35:47 [cube] test_cube_weighting_taper: 
22-Jul-2025 08:35:47 PASSED
22-Jul-2025 08:35:47
22-Jul-2025 08:35:48 test_task_tclean.py::test_cube::test_cube_weighting_taper_cas13260 2025-07-22 12:35:49        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:36:10 2025-07-22 12:36:11        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:36:37 2025-07-22 12:36:37        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:36:57 2025-07-22 12:36:58        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:37:18
22-Jul-2025 08:37:18 [cube] test_cube_weighting_taper_cas13260: 
22-Jul-2025 08:37:18 PASSED
22-Jul-2025 08:37:18
22-Jul-2025 08:37:18 test_task_tclean.py::test_cube::test_cube_weighting_taper_cas13260_old
22-Jul-2025 08:37:18 [cube] test_cube_weighting_taper_cas1326_old: 
22-Jul-2025 08:37:18 SKIPPED (Skip until we can figure out what causes the common bean di...)
22-Jul-2025 08:37:18
22-Jul-2025 08:37:34 test_task_tclean.py::test_cube::test_cubedata_briggs
22-Jul-2025 08:37:34 [cube] test_cubedata_briggs : specmode cubedata with perchanweightdensity- No runtime doppler corrections 
22-Jul-2025 08:37:34 PASSED
22-Jul-2025 08:37:34
22-Jul-2025 08:37:34 test_task_tclean.py::test_cube::test_cubedata_mtmfs_nterms1
22-Jul-2025 08:37:34 [cube] Test mtmfs with cube data and nterms = 1 
22-Jul-2025 08:37:34 SKIPPED (Skip. This test deprecated. no longer need mtmfs for cube u...)
22-Jul-2025 08:37:34
22-Jul-2025 08:38:03 test_task_tclean.py::test_mask::test_mask_1
22-Jul-2025 08:38:03 [mask] test_mask_1 : Input mask as file and string : mfs 
22-Jul-2025 08:38:03 PASSED
22-Jul-2025 08:38:03
22-Jul-2025 08:38:04 test_task_tclean.py::test_mask::test_mask_2 2025-07-22 12:38:05        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:38:27 2025-07-22 12:38:28        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:38:48
22-Jul-2025 08:38:48 [mask] test_mask_2 :  Input mask as file and string : cube (few channels) 
22-Jul-2025 08:38:48 PASSED
22-Jul-2025 08:38:48
22-Jul-2025 08:39:09 test_task_tclean.py::test_mask::test_mask_3
22-Jul-2025 08:39:09 [mask] test_mask_3 : Input mask as image-to-be-regridded (ra/dec) : mfs 
22-Jul-2025 08:39:09 PASSED
22-Jul-2025 08:39:09
22-Jul-2025 08:39:10 test_task_tclean.py::test_mask::test_mask_4 2025-07-22 12:39:10        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:39:34 2025-07-22 12:39:34        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:39:37 2025-07-22 12:39:37        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2136)        PSF is blank for[C13:P0]
22-Jul-2025 08:39:41 2025-07-22 12:39:42        WARN        ImageRegridder::_regrid        Warning: template/imagename relative channel size is 1
22-Jul-2025 08:39:41 2025-07-22 12:39:42        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.
22-Jul-2025 08:39:56
22-Jul-2025 08:39:56 [mask] test_mask_4 :  Input mask as image-to-be-regridded(ra/dec/specframe) : cube 
22-Jul-2025 08:39:56 PASSED
22-Jul-2025 08:39:56
22-Jul-2025 08:39:57 test_task_tclean.py::test_mask::test_mask_5 2025-07-22 12:39:57        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:40:11 2025-07-22 12:40:12        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:40:18 2025-07-22 12:40:18        WARN        ImageRegridder::_regrid        Warning: template/imagename relative channel size is 1
22-Jul-2025 08:40:18 2025-07-22 12:40:18        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.
22-Jul-2025 08:40:25
22-Jul-2025 08:40:25 [mask] test_mask_5 : Input cube mask that has different chan
22-Jul-2025 08:40:25 ranges (use mask from the 1st tclean with a different channel range in the 2nd tclean run)
22-Jul-2025 08:40:25 PASSED
22-Jul-2025 08:40:25
22-Jul-2025 08:40:38 test_task_tclean.py::test_mask::test_mask_autobox_multithresh
22-Jul-2025 08:40:38 [mask] test_mask__autobox_multithresh :  multi-threshold Autobox (default)
22-Jul-2025 08:40:38 PASSED
22-Jul-2025 08:40:38
22-Jul-2025 08:40:48 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_cube_IQUV
22-Jul-2025 08:40:48 [mask] test_mask__autobox_multithresh_cube_IQUV :  multi-threshold Autobox (minbeamfrac=0.05) with cube full polarizaiton (IQUV) imaging 
22-Jul-2025 08:40:48 PASSED
22-Jul-2025 08:40:48
22-Jul-2025 08:40:55 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_mfs_IQUV
22-Jul-2025 08:40:55 [mask] test_mask__autobox_multithresh_mtmfs_IQUV :  multi-threshold Autobox (minbeamfrac=0.3) with cube full polarizaiton (IQUV) imaging 
22-Jul-2025 08:40:55 PASSED
22-Jul-2025 08:40:55
22-Jul-2025 08:41:06 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_newnoise
22-Jul-2025 08:41:06 [mask] test_mask__autobox_multithresh_newnoise :  multi-threshold Autobox invoking the new noise calc.
22-Jul-2025 08:41:06 PASSED
22-Jul-2025 08:41:06
22-Jul-2025 08:41:32 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_absorption
22-Jul-2025 08:41:32 [mask] test_mask__autobox_multithresh_on_absorption :  multi-threshold Autobox (minbeamfrac=0.3) on the data with both emission and absorption   
22-Jul-2025 08:41:32 PASSED
22-Jul-2025 08:41:32
22-Jul-2025 08:41:48 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_nsigma
22-Jul-2025 08:41:48 [mask] test_mask__autobox_multithresh :  multi-threshold Autobox invoking the new noise calc.
22-Jul-2025 08:41:48 PASSED
22-Jul-2025 08:41:48
22-Jul-2025 08:41:58 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_nsigma_newnoise
22-Jul-2025 08:41:58 [mask] test_mask__autobox_multithresh :  multi-threshold Autobox invoking the new noise calc.
22-Jul-2025 08:41:58 PASSED
22-Jul-2025 08:41:58
22-Jul-2025 08:42:15 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_prune
22-Jul-2025 08:42:15 [mask] test_mask__autobox_multithresh_with_prune :  multi-threshold Autobox (minbeamfrac=0.3)
22-Jul-2025 08:42:15 PASSED
22-Jul-2025 08:42:15
22-Jul-2025 08:42:29 test_task_tclean.py::test_mask::test_mask_autobox_multithresh_with_stopmask
22-Jul-2025 08:42:29 [mask] test_mask__autobox_multithresh_with_stopmask :  multi-threshold Autobox (minbeamfrac=0.3) with stop mask on 
22-Jul-2025 08:42:29 PASSED
22-Jul-2025 08:42:29
22-Jul-2025 08:42:29 test_task_tclean.py::test_mask::test_mask_combined_1
22-Jul-2025 08:42:29 [mask] test_mask_combined_1 :  string + pbmask 
22-Jul-2025 08:42:29 SKIPPED (Skip test.)
22-Jul-2025 08:42:29
22-Jul-2025 08:42:29 test_task_tclean.py::test_mask::test_mask_combined_2
22-Jul-2025 08:42:29 [mask] test_mask_combined_2 :  Autobox + pbmask 
22-Jul-2025 08:42:29 SKIPPED (Skip test.)
22-Jul-2025 08:42:29
22-Jul-2025 08:42:37 test_task_tclean.py::test_mask::test_mask_expand_contstokesIQUVmask_nodegen_to_cube_IQUV
22-Jul-2025 08:42:37 [mask] test_mask_expand_contstokesIQUVmask_nodegen_to_cube_IQUV : Test for expanding
22-Jul-2025 08:42:37 input continuum Stokes IQUV mask with its degenerate axes removed to cube IQUV imaging   
22-Jul-2025 08:42:37 PASSED
22-Jul-2025 08:42:37
22-Jul-2025 08:42:47 test_task_tclean.py::test_mask::test_mask_expand_contstokesIQUVmask_to_cube_IQUV
22-Jul-2025 08:42:47 [mask] test_mask_expand_contstokesIQUVmask_to_cube_IQUV : Test for expanding
22-Jul-2025 08:42:47 input continuum Stokes IQUV mask to cube IQUV imaging   
22-Jul-2025 08:42:47 PASSED
22-Jul-2025 08:42:47
22-Jul-2025 08:42:57 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_nodegen_to_IQUV
22-Jul-2025 08:42:57 [mask] test_mask_expand_contstokesImask_nodegen_to_IQUV : Test for expanding
22-Jul-2025 08:42:57 input continuum Stokes I mask with its degenerate axes removed to continuum multi-stokes imaging   
22-Jul-2025 08:42:57 PASSED
22-Jul-2025 08:42:57
22-Jul-2025 08:43:02 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_nodegen_to_cube
22-Jul-2025 08:43:02 [mask] test_mask_expand_contstokesImask_nodegen_to_cube : Test for
22-Jul-2025 08:43:02 expanding input continuum Stokes I mask with its degenerate axes removed to cube imaging   
22-Jul-2025 08:43:02 PASSED
22-Jul-2025 08:43:02
22-Jul-2025 08:43:11 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_nodegen_to_cube_IQUV
22-Jul-2025 08:43:11 [mask] test_mask_extend_contstokesImask_nodegen_to_cube_IQUV : Test for extending
22-Jul-2025 08:43:11 input continuum Stokes I mask with its denenerate axes removed to cube multi-stokes imaging   
22-Jul-2025 08:43:11 PASSED
22-Jul-2025 08:43:11
22-Jul-2025 08:43:16 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_to_IQUV
22-Jul-2025 08:43:16 [mask] test_mask_expand_contstokesImask_to_IQUV : Test for expanding
22-Jul-2025 08:43:16 input continuum Stokes I mask to continuum multi-stokes imaging   
22-Jul-2025 08:43:16 PASSED
22-Jul-2025 08:43:16
22-Jul-2025 08:43:26 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_to_cube
22-Jul-2025 08:43:26 [mask] test_mask_expand_contstokesImask_to_cube : Test for
22-Jul-2025 08:43:26 expanding input continuum Stokes I mask to cube imaging   
22-Jul-2025 08:43:26 PASSED
22-Jul-2025 08:43:26
22-Jul-2025 08:43:34 test_task_tclean.py::test_mask::test_mask_expand_contstokesImask_to_cube_IQUV
22-Jul-2025 08:43:34 [mask] test_mask_extend_contstokesImask_to_cube_IQUV : Test for extending
22-Jul-2025 08:43:34 input continuum Stokes I mask to cube multi-stokes imaging   
22-Jul-2025 08:43:34 PASSED
22-Jul-2025 08:43:34
22-Jul-2025 08:43:42 test_task_tclean.py::test_mask::test_mask_expand_cubestokesImask_nodegen_to_cube_IQUV
22-Jul-2025 08:43:42 [mask] test_mask_expand_contstokesIQUVmask_nodegen_to_cube_IQUV : Test for expanding
22-Jul-2025 08:43:42 input cube Stokes I mask with its degenerate axes removed to cube (of the same spectral coordinates)  IQUV imaging   
22-Jul-2025 08:43:42 PASSED
22-Jul-2025 08:43:42
22-Jul-2025 08:43:49 test_task_tclean.py::test_mask::test_mask_expand_cubestokesImask_to_cube_IQUV
22-Jul-2025 08:43:49 [mask] test_mask_expand_contstokesIQUVmask_to_cube_IQUV : Test for expanding
22-Jul-2025 08:43:49 input cube Stokes I mask to cube (of the same spectral coordinates)  IQUV imaging   
22-Jul-2025 08:43:49 PASSED
22-Jul-2025 08:43:49
22-Jul-2025 08:43:50 test_task_tclean.py::test_mask::test_mask_long_region_specification 2025-07-22 12:43:51        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 08:44:09 2025-07-22 12:44:10        WARN        ImageRegridder::_doImagesOverlap        At least one of the images exceeds one degree on at one side, not checking for direction plane overlap.
22-Jul-2025 08:44:24
22-Jul-2025 08:44:24 [mask] test_mask_long_region_specification : Test the fix for CAS-13624   
22-Jul-2025 08:44:24 report= [ check_ret_structure ] Return dictionary struture check: type=reduced ( Pass : found all expected keys  )
22-Jul-2025 08:44:24 [ check_ims ] Image made : ['tst.mask', 'tst.image'] =  [True, True] ( Pass : should all be True )
22-Jul-2025 08:44:24 All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
22-Jul-2025 08:44:24 [check_history] All expected history entries found. (Pass)
22-Jul-2025 08:44:24 [ check_pix_val ] tst.mask : Value is 1.0 at [256, 256, 0, 0] ( Pass : should be 1.0 , Epsilon: 0.05)
22-Jul-2025 08:44:24 [ check_pix_val ] tst.mask : Value is 1.0 at [255, 253, 0, 3] ( Pass : should be 1.0 , Epsilon: 0.05)
22-Jul-2025 08:44:24 [ check_pix_val ] tst.mask : Value is 0.0 at [260, 259, 0, 0] ( Pass : should be 0.0 , Epsilon: 0.05)
22-Jul-2025 08:44:24 [ check_logs ]: found 1 matching log lines (Pass)
22-Jul-2025 08:44:24
22-Jul-2025 08:44:24 PASSED
22-Jul-2025 08:44:24
22-Jul-2025 08:44:24 test_task_tclean.py::test_mask::test_mask_outlier
22-Jul-2025 08:44:24 [mask] test_mask_outlier : With outlier fields 
22-Jul-2025 08:44:24 SKIPPED (Skip test.)
22-Jul-2025 08:44:24
22-Jul-2025 08:44:24 test_task_tclean.py::test_mask::test_mask_pbmask
22-Jul-2025 08:44:24 [mask] test_mask_pbmask :  pb mask 
22-Jul-2025 08:44:24 SKIPPED (Skip test.)
22-Jul-2025 08:44:24
22-Jul-2025 08:44:39 test_task_tclean.py::test_mask::test_mask_preserve_input_zero_mask 2025-07-22 12:44:39        WARN        SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 08:44:40
22-Jul-2025 08:44:40 Test the fix for CAS-14203; If a user explicitly provides a
22-Jul-2025 08:44:40 zero-filled input mask, it should be respected and not flipped.
22-Jul-2025 08:44:40 PASSED
22-Jul-2025 08:44:40
22-Jul-2025 08:44:48 test_task_tclean.py::test_mask::test_mask_zerostart 2025-07-22 12:44:49        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 08:44:57 2025-07-22 12:44:58        WARN        SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 08:44:59
22-Jul-2025 08:44:59 [mask] test_mask_zerostart : Test that a zero starting mask is caught   
22-Jul-2025 08:44:59 len(summ_minor)= 1
22-Jul-2025 08:44:59 [check_stopcode] Stopcode is 7 (Pass : should be 7)
22-Jul-2025 08:44:59
22-Jul-2025 08:44:59 PASSED
22-Jul-2025 08:44:59
22-Jul-2025 08:46:05 test_task_tclean.py::test_wproject::test_wterm_awp2
22-Jul-2025 08:46:16 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:46:35
22-Jul-2025 08:47:26 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:47:55
22-Jul-2025 08:49:49 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:49:56
22-Jul-2025 08:49:56 [wproject] Test_Widefield_wproj : W-Projection using the AWP2 gridder 
22-Jul-2025 08:49:56 PASSED
22-Jul-2025 08:49:56
22-Jul-2025 08:49:56 test_task_tclean.py::test_wproject::test_wterm_awphpg
22-Jul-2025 08:49:56 [wproject] Test_Widefield_wproj : W-Projection using the hpg AWProject gridder 
22-Jul-2025 08:49:56 SKIPPED (Not correct GPU or MPI is not compatible with hpg)
22-Jul-2025 08:49:56
22-Jul-2025 08:50:13 test_task_tclean.py::test_wproject::test_wterm_awproject
22-Jul-2025 08:52:48 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:52:48
22-Jul-2025 08:52:48 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:52:48 2025-07-22 12:52:49        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
22-Jul-2025 08:52:48 2025-07-22 12:52:49        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 08:53:02 2025-07-22 12:53:02        WARN        AWProjectFT::finalizeToSky        time to massage data 183.75
22-Jul-2025 08:53:02 2025-07-22 12:53:02        WARN        AWProjectFT::finalizeToSky        time gridding 0.29
22-Jul-2025 08:53:07 2025-07-22 12:53:08        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.31
22-Jul-2025 08:53:07 2025-07-22 12:53:08        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.06
22-Jul-2025 08:53:09 2025-07-22 12:53:10        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 08:53:11
22-Jul-2025 08:53:11 [wproject] Test_Widefield_wproj : W-Projection using the AWProject gridder 
22-Jul-2025 08:53:11 PASSED
22-Jul-2025 08:53:11
22-Jul-2025 08:53:29 test_task_tclean.py::test_wproject::test_wterm_facets 2025-07-22 12:53:29        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 08:53:31
22-Jul-2025 08:53:31 [wproject] Test_Widefield_wproj : Facets 
22-Jul-2025 08:53:31 PASSED
22-Jul-2025 08:53:31
22-Jul-2025 08:53:32 test_task_tclean.py::test_wproject::test_wterm_squint_awp2 paMax 3.13298 painc 0.261799
22-Jul-2025 08:56:32
22-Jul-2025 08:56:33 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:56:55
22-Jul-2025 08:56:59 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:57:20
22-Jul-2025 08:57:37 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 08:57:47
22-Jul-2025 08:57:47 [wproject] Test_Widefield_wproj : W-Projection with squint using the AWP2 gridder 
22-Jul-2025 08:57:47 [ checkall ]
22-Jul-2025 08:57:47 [ check_ims ] Image made : ['tst.awp2.image'] =  [True] ( Pass : should all be True )
22-Jul-2025 08:57:47 All expected keywords in imageinfo, miscinfo, and coords found. (check_keywords)
22-Jul-2025 08:57:47 [check_history] All expected history entries found. (Pass)
22-Jul-2025 08:57:47 [ check_pix_val ] tst.awp2.pb : Value is 0.6809056401252747 at [323, 858, 0, 0] ( Pass : should be 0.654 , Epsilon: 0.05)
22-Jul-2025 08:57:47 [ check_pix_val ] tst.awp2.image.pbcor : Value is 0.6786918044090271 at [323, 858, 0, 0] ( Pass : should be 0.7 , Epsilon: 0.05)
22-Jul-2025 08:57:47 [ check_pix_val ] tst.awp2.image.pbcor : Value is -0.009907497093081474 at [323, 858, 1, 0] ( Pass : should be -0.01 , Epsilon: 0.05)
22-Jul-2025 08:57:47
22-Jul-2025 08:57:47 PASSED
22-Jul-2025 08:57:47
22-Jul-2025 08:58:08 test_task_tclean.py::test_wproject::test_wterm_wproject 2025-07-22 12:58:08        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 08:58:10
22-Jul-2025 08:58:10 [wproject] Test_Widefield_wproj : W-Projection 
22-Jul-2025 08:58:10 [check_tfmask]  T/F mask name for tst.wyes.image is : ['T'] (Pass : should be ['T'])
22-Jul-2025 08:58:10
22-Jul-2025 08:58:10 PASSED
22-Jul-2025 08:58:10
22-Jul-2025 08:58:31 test_task_tclean.py::test_wproject::test_wterm_wproject_facets 2025-07-22 12:58:32        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 08:58:33
22-Jul-2025 08:58:33 [wproject] Test_Widefield_wproj : Facets with wprojection per facet
22-Jul-2025 08:58:33 PASSED
22-Jul-2025 08:58:33
22-Jul-2025 08:58:49 test_task_tclean.py::test_widefield::test_mosaicft_newpsfphasecenter
22-Jul-2025 08:58:49 test_mosaicft_newpsfphasecenter : different phasecenter for psf
22-Jul-2025 08:58:49 PASSED
22-Jul-2025 08:58:49
22-Jul-2025 08:59:19 test_task_tclean.py::test_widefield::test_mosaicft_newpsfphasecenter_cube
22-Jul-2025 08:59:19 test_mosaicft_newpsfphasecenter_cube : different phasecenter for psf
22-Jul-2025 08:59:19 PASSED
22-Jul-2025 08:59:19
22-Jul-2025 08:59:20 test_task_tclean.py::test_widefield::test_widefield_aproj_cube 2025-07-22 12:59:21        WARN        SynthesisImagerVi2::dryGridding (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 3148)        Making a "blank" CFCache
22-Jul-2025 08:59:20 2025-07-22 12:59:21        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
22-Jul-2025 08:59:20 2025-07-22 12:59:21        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 08:59:28 2025-07-22 12:59:28        WARN        SynthesisImagerVi2::fillCFCache (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisImagerVi2.cc, line 3232)        Re-loading the "blank" CFCache for filling
22-Jul-2025 08:59:28 2025-07-22 12:59:28        WARN        crustaceans::makeConvFunction2[R&D]        Filling CFs for baseline type 0, PA slot 0
22-Jul-2025 08:59:28 Processing request for geometries from observatory EVLA, antenna type STANDARD
22-Jul-2025 09:01:17 2025-07-22 13:01:17        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
22-Jul-2025 09:01:17 2025-07-22 13:01:17        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 09:01:18 2025-07-22 13:01:18        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.59
22-Jul-2025 09:01:18 2025-07-22 13:01:18        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.27
22-Jul-2025 09:01:20 2025-07-22 13:01:20        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.45
22-Jul-2025 09:01:20 2025-07-22 13:01:20        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.07
22-Jul-2025 09:01:23 2025-07-22 13:01:24        WARN        task_tclean::AWProjectFT::finalizeToVis        Time degrid 0.1
22-Jul-2025 09:01:23 2025-07-22 13:01:24        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.21
22-Jul-2025 09:01:23 2025-07-22 13:01:24        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.12
22-Jul-2025 09:01:26
22-Jul-2025 09:01:26 [widefield] Test_Widefield_aproj_cube_aproj : Cube with AW-Projection  and rotation off 
22-Jul-2025 09:01:26 PASSED
22-Jul-2025 09:01:26
22-Jul-2025 09:02:02 test_task_tclean.py::test_widefield::test_widefield_aproj_mfs
22-Jul-2025 09:02:02 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:02:02
22-Jul-2025 09:02:02 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:02:02 2025-07-22 13:02:03        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
22-Jul-2025 09:02:02 2025-07-22 13:02:03        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 09:02:04 2025-07-22 13:02:04        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 36.09
22-Jul-2025 09:02:04 2025-07-22 13:02:04        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.09
22-Jul-2025 09:02:14
22-Jul-2025 09:02:15 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:02:15 2025-07-22 13:02:16        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.44
22-Jul-2025 09:02:15 2025-07-22 13:02:16        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.08
22-Jul-2025 09:02:17 2025-07-22 13:02:17        WARN        task_tclean::AWProjectFT::finalizeToVis        Time degrid 0.04
22-Jul-2025 09:02:17 2025-07-22 13:02:17        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.17
22-Jul-2025 09:02:17 2025-07-22 13:02:17        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.03
22-Jul-2025 09:02:25
22-Jul-2025 09:02:29 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:02:29 2025-07-22 13:02:29        WARN        task_tclean::AWProjectFT::finalizeToVis        Time degrid 0.07
22-Jul-2025 09:02:29 2025-07-22 13:02:29        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.25
22-Jul-2025 09:02:29 2025-07-22 13:02:29        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.09
22-Jul-2025 09:02:31
22-Jul-2025 09:02:31 [widefield] Test_Widefield_aproj : MFS with narrowband AWProjection (wbawp=F, 1spw)  stokes I 
22-Jul-2025 09:02:31 PASSED
22-Jul-2025 09:02:31
22-Jul-2025 09:03:07 test_task_tclean.py::test_widefield::test_widefield_aproj_mtmfs
22-Jul-2025 09:04:17 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:04:17
22-Jul-2025 09:04:17 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:04:17 2025-07-22 13:04:17        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 0 during gridding to compute sensitivity pattern.
22-Jul-2025 09:04:17 2025-07-22 13:04:17        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 09:04:22 2025-07-22 13:04:22        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 1 during gridding to compute sensitivity pattern.
22-Jul-2025 09:04:22 2025-07-22 13:04:22        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 09:04:22 2025-07-22 13:04:22        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 2 during gridding to compute sensitivity pattern.
22-Jul-2025 09:04:22 2025-07-22 13:04:22        WARN        AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 09:04:24 2025-07-22 13:04:25        WARN        AWProjectFT::finalizeToSky        time to massage data 109.7
22-Jul-2025 09:04:24 2025-07-22 13:04:25        WARN        AWProjectFT::finalizeToSky        time gridding 0.4
22-Jul-2025 09:04:25 2025-07-22 13:04:26        WARN        AWProjectFT::finalizeToSky        time to massage data 0.3
22-Jul-2025 09:04:25 2025-07-22 13:04:26        WARN        AWProjectFT::finalizeToSky        time gridding 0.34
22-Jul-2025 09:04:27 2025-07-22 13:04:27        WARN        AWProjectFT::finalizeToSky        time to massage data 0.19
22-Jul-2025 09:04:27 2025-07-22 13:04:27        WARN        AWProjectFT::finalizeToSky        time gridding 0.37
22-Jul-2025 09:04:34 2025-07-22 13:04:34        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.6
22-Jul-2025 09:04:34 2025-07-22 13:04:34        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.1
22-Jul-2025 09:04:34 2025-07-22 13:04:34        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.31
22-Jul-2025 09:04:34 2025-07-22 13:04:34        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.08
22-Jul-2025 09:04:36 2025-07-22 13:04:37        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 09:04:36 2025-07-22 13:04:37        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 09:04:41
22-Jul-2025 09:04:41 [widefield] Test_Widefield_aproj_mtmfs : MFS with AWProjection (wbawp=T,conjbeams=F, allspw) and nt=2 stokes I   
22-Jul-2025 09:04:41 PASSED
22-Jul-2025 09:04:41
22-Jul-2025 09:04:55 test_task_tclean.py::test_widefield::test_widefield_awp2_cube
22-Jul-2025 09:05:03 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:05:17
22-Jul-2025 09:05:25 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:05:40
22-Jul-2025 09:06:03 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:06:07
22-Jul-2025 09:06:07 [widefield] Test_Widefield_awp2_cube : Cube with AW-Projection  and rotation off 
22-Jul-2025 09:06:07 PASSED
22-Jul-2025 09:06:07
22-Jul-2025 09:06:23 test_task_tclean.py::test_widefield::test_widefield_awp2_mfs
22-Jul-2025 09:06:32 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:06:40
22-Jul-2025 09:06:40 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:06:48
22-Jul-2025 09:07:03 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:07:07
22-Jul-2025 09:07:07 [widefield] Test_Widefield_awp2 : MFS with narrowband AWProjection (1spw)  stokes I 
22-Jul-2025 09:07:07 PASSED
22-Jul-2025 09:07:07
22-Jul-2025 09:07:07 test_task_tclean.py::test_widefield::test_widefield_awp2_mtmfs 2025-07-22 13:07:07        WARN        tclean::task_tclean::casa        Please consider using specmode=mvc with awp2 gridder as this gridder does not implement conjbeams
22-Jul-2025 09:07:07 2025-07-22 13:07:07        WARN        tclean::task_tclean::casa+         thus it needs a few major cycles to converge towards the correct answer
22-Jul-2025 09:07:20
22-Jul-2025 09:07:56 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:08:09
22-Jul-2025 09:08:32 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:08:41
22-Jul-2025 09:09:33 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:09:40
22-Jul-2025 09:09:40 [widefield] Test_Widefield_wbaproj_mtmfs : MFS with wideband AWProjection (wbawp=T,conjbeams=T, allspw) and nt=2 stokes I   
22-Jul-2025 09:09:40 PASSED
22-Jul-2025 09:09:40
22-Jul-2025 09:09:40 test_task_tclean.py::test_widefield::test_widefield_awphpg_cube
22-Jul-2025 09:09:40 [widefield] Test_Widefield_awphpg_cube : Cube with AW-Projection  and rotation off 
22-Jul-2025 09:09:40 SKIPPED (Not Correct GPU or MPI is not compatible with hpg)
22-Jul-2025 09:09:40
22-Jul-2025 09:09:40 test_task_tclean.py::test_widefield::test_widefield_awphpg_mfs
22-Jul-2025 09:09:40 [widefield] Test_Widefield_awphpg : MFS with narrowband AWProjection 1spw  stokes I 
22-Jul-2025 09:09:40 SKIPPED (Not Correct GPU or MPI is not compatible with hpg)
22-Jul-2025 09:09:40
22-Jul-2025 09:09:40 test_task_tclean.py::test_widefield::test_widefield_awphpg_mtmfs_via_cube
22-Jul-2025 09:09:40 [widefield] Test_Widefield_wbaproj_mtmfs : MFS with wideband AWProjection (wbawp=T,conjbeams=T, allspw) and nt=2 stokes I   
22-Jul-2025 09:09:40 SKIPPED (Not correct GPU or MPI is not compatible with hpg)
22-Jul-2025 09:09:40
22-Jul-2025 09:09:57 test_task_tclean.py::test_widefield::test_widefield_mosaicft_cube
22-Jul-2025 09:09:57 [widefield] Test_Widefield_mosaicft_cube : MFS with mosaicft  stokes I 
22-Jul-2025 09:09:57 PASSED
22-Jul-2025 09:09:57
22-Jul-2025 09:10:12 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mfs
22-Jul-2025 09:10:12 [widefield] Test_Widefield_mosaic : MFS with mosaicft  stokes I 
22-Jul-2025 09:10:12 [check_tfmask]  T/F mask name for tst.image is : ['mask0'] (Pass : should be ['mask0'])
22-Jul-2025 09:10:12
22-Jul-2025 09:10:12 PASSED
22-Jul-2025 09:10:12
22-Jul-2025 09:10:33 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mfs_mosweightTrue
22-Jul-2025 09:10:38 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:10:51 2025-07-22 13:10:52        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 09:11:03 2025-07-22 13:11:04        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 09:11:05
22-Jul-2025 09:11:05 [widefield] Test_Widefield_mosaic : MFS with mosaicft  stokes I and IQUV briggs mosweight=True(default)
22-Jul-2025 09:11:05 PASSED
22-Jul-2025 09:11:05
22-Jul-2025 09:11:13 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mtmfs
22-Jul-2025 09:11:13 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:11:36
22-Jul-2025 09:11:36 [widefield] Test_Widefield_mosaicft_mtmfs : MT-MFS with mosaicft  stokes I, alpha 
22-Jul-2025 09:11:36 [check_tfmask]  T/F mask name for tst.image.tt0 is : ['mask0'] (Pass : should be ['mask0'])
22-Jul-2025 09:11:36
22-Jul-2025 09:11:36 PASSED
22-Jul-2025 09:11:36
22-Jul-2025 09:12:08 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mtmfs_conj
22-Jul-2025 09:12:08 [widefield] Test_Widefield_mosaicft_mtmfs : MT-MFS with mosaicft  stokes I, alpha 
22-Jul-2025 09:12:08 PASSED
22-Jul-2025 09:12:08
22-Jul-2025 09:12:09 test_task_tclean.py::test_widefield::test_widefield_mosaicft_mtmfs_pbsquare 2025-07-22 13:12:09        WARN        tclean::task_tclean::casa        Please consider using specmode=mvc with mosaic gridder as this gridder does not implement conjbeams
22-Jul-2025 09:12:09 2025-07-22 13:12:09        WARN        tclean::task_tclean::casa+         thus it needs a few major cycles to converge towards the correct answer
22-Jul-2025 09:12:17
22-Jul-2025 09:12:17 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:12:41
22-Jul-2025 09:12:41 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:12:46
22-Jul-2025 09:12:46 [widefield] Test_Widefield_mosaicft_mtmfs : MT-MFS with mosaicft  stokes I, alpha 
22-Jul-2025 09:12:46 PASSED
22-Jul-2025 09:12:46
22-Jul-2025 09:12:51 test_task_tclean.py::test_widefield::test_widefield_mvc_squint_awp2 paMax 3.13332 painc 0.261799
22-Jul-2025 09:12:51 Processing request for geometries from observatory VLA, antenna type STANDARD
22-Jul-2025 09:16:36
22-Jul-2025 09:16:37 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:17:02 paMax 3.13332 painc 0.261799
22-Jul-2025 09:20:48
22-Jul-2025 09:21:04 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:21:26 paMax 3.13332 painc 0.261799
22-Jul-2025 09:25:10
22-Jul-2025 09:25:53 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:26:14
22-Jul-2025 09:26:14 [wproject] Test_Widefield_wproj : W-Projection with squint using the AWP2 gridder 
22-Jul-2025 09:26:14 PASSED
22-Jul-2025 09:26:14
22-Jul-2025 09:26:15 test_task_tclean.py::test_widefield::test_widefield_wbaproj_mfs Processing request for geometries from observatory EVLA, antenna type STANDARD
22-Jul-2025 09:26:49
22-Jul-2025 09:27:53 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:27:53
22-Jul-2025 09:27:53 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:27:53 2025-07-22 13:27:54        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation during gridding to compute sensitivity pattern.
22-Jul-2025 09:27:53 2025-07-22 13:27:54        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 09:27:58 2025-07-22 13:27:58        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 101.55
22-Jul-2025 09:27:58 2025-07-22 13:27:58        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.39
22-Jul-2025 09:28:01 2025-07-22 13:28:02        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.49
22-Jul-2025 09:28:01 2025-07-22 13:28:02        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.05
22-Jul-2025 09:28:04 2025-07-22 13:28:05        WARN        task_tclean::AWProjectFT::finalizeToVis        Time degrid 0.09
22-Jul-2025 09:28:04 2025-07-22 13:28:05        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.15
22-Jul-2025 09:28:04 2025-07-22 13:28:05        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.06
22-Jul-2025 09:28:07 2025-07-22 13:28:07        WARN        task_tclean::AWProjectFT::finalizeToVis        Time degrid 0.09
22-Jul-2025 09:28:07 2025-07-22 13:28:07        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.18
22-Jul-2025 09:28:07 2025-07-22 13:28:07        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.1
22-Jul-2025 09:28:09
22-Jul-2025 09:28:09 [widefield] Test_Widefield_wbaproj_mfs : MFS with wideband AWProjection (wbawp=T, allspw) and nt=1 stokes I   
22-Jul-2025 09:28:09 PASSED
22-Jul-2025 09:28:09
22-Jul-2025 09:28:44 test_task_tclean.py::test_widefield::test_widefield_wbaproj_mtmfs
22-Jul-2025 09:29:52 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:29:52
22-Jul-2025 09:29:52 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:29:52 2025-07-22 13:29:52        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 0 during gridding to compute sensitivity pattern.
22-Jul-2025 09:29:52 2025-07-22 13:29:52        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 09:29:56 2025-07-22 13:29:56        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 1 during gridding to compute sensitivity pattern.
22-Jul-2025 09:29:56 2025-07-22 13:29:56        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 09:29:56 2025-07-22 13:29:56        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]        Setting up for weights accumulation for term 2 during gridding to compute sensitivity pattern.
22-Jul-2025 09:29:56 2025-07-22 13:29:56        WARN        task_tclean::AWProjectWBFT2::makeSensitivityImage(Complex)[R&D]+        Consequently, the first gridding cycle will be slower than the subsequent ones.
22-Jul-2025 09:29:58 2025-07-22 13:29:59        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 105.83
22-Jul-2025 09:29:58 2025-07-22 13:29:59        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.49
22-Jul-2025 09:29:59 2025-07-22 13:29:59        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.21
22-Jul-2025 09:29:59 2025-07-22 13:29:59        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.32
22-Jul-2025 09:30:00 2025-07-22 13:30:01        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.26
22-Jul-2025 09:30:00 2025-07-22 13:30:01        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.31
22-Jul-2025 09:30:05 2025-07-22 13:30:06        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.36
22-Jul-2025 09:30:05 2025-07-22 13:30:06        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.1
22-Jul-2025 09:30:06 2025-07-22 13:30:07        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.29
22-Jul-2025 09:30:06 2025-07-22 13:30:07        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.07
22-Jul-2025 09:30:11 2025-07-22 13:30:11        WARN        task_tclean::AWProjectFT::finalizeToVis        Time degrid 0.1
22-Jul-2025 09:30:11 2025-07-22 13:30:11        WARN        task_tclean::AWProjectFT::finalizeToVis        Time degrid 0.08
22-Jul-2025 09:30:11 2025-07-22 13:30:11        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.3
22-Jul-2025 09:30:11 2025-07-22 13:30:11        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.12
22-Jul-2025 09:30:11 2025-07-22 13:30:12        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.19
22-Jul-2025 09:30:11 2025-07-22 13:30:12        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.08
22-Jul-2025 09:30:16 2025-07-22 13:30:16        WARN        task_tclean::AWProjectFT::finalizeToVis        Time degrid 0.09
22-Jul-2025 09:30:16 2025-07-22 13:30:16        WARN        task_tclean::AWProjectFT::finalizeToVis        Time degrid 0.09
22-Jul-2025 09:30:16 2025-07-22 13:30:16        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.34
22-Jul-2025 09:30:16 2025-07-22 13:30:16        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.09
22-Jul-2025 09:30:16 2025-07-22 13:30:17        WARN        task_tclean::AWProjectFT::finalizeToSky        time to massage data 0.26
22-Jul-2025 09:30:16 2025-07-22 13:30:17        WARN        task_tclean::AWProjectFT::finalizeToSky        time gridding 0.1
22-Jul-2025 09:30:22
22-Jul-2025 09:30:22 [widefield] Test_Widefield_wbaproj_mtmfs : MFS with wideband AWProjection (wbawp=T,conjbeams=T, allspw) and nt=2 stokes I   
22-Jul-2025 09:30:22 PASSED
22-Jul-2025 09:30:22
22-Jul-2025 09:30:36 test_task_tclean.py::test_modelvis::test_modelvis_1
22-Jul-2025 09:30:36 [modelpredict] Test_modelvis_1 : mfs with no save model 
22-Jul-2025 09:30:36 PASSED
22-Jul-2025 09:30:36
22-Jul-2025 09:30:49 test_task_tclean.py::test_modelvis::test_modelvis_10
22-Jul-2025 09:31:06 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:31:19
22-Jul-2025 09:31:37 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:31:55
22-Jul-2025 09:32:21 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:32:37
22-Jul-2025 09:32:37 [modelpredict] Test_modelvis_10 : Use input model of different (narrower) freq range than data 
22-Jul-2025 09:32:37 [check_chanvals] Chan 3  is (0.9999999999999999+0j) (Pass : should be ==1.0)
22-Jul-2025 09:32:37
22-Jul-2025 09:32:37 [check_chanvals] Chan 3  is (0.6942970446700978+0j) (Pass : should be >0.0)
22-Jul-2025 09:32:37
22-Jul-2025 09:32:37 PASSED
22-Jul-2025 09:32:37
22-Jul-2025 09:32:50 test_task_tclean.py::test_modelvis::test_modelvis_11
22-Jul-2025 09:33:20 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:33:29
22-Jul-2025 09:33:58 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:34:17
22-Jul-2025 09:34:46 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:35:03
22-Jul-2025 09:35:03 [modelpredict] Test_modelvis_11 : Predict model image over channel gaps not included in imaging 
22-Jul-2025 09:35:03 PASSED
22-Jul-2025 09:35:03
22-Jul-2025 09:35:21 test_task_tclean.py::test_modelvis::test_modelvis_12
22-Jul-2025 09:35:44 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:35:47
22-Jul-2025 09:35:47 [modelpredict] Test_modelvis_12 : (CAS-12618) mfs with automask and save model column (single tclean call, internally a separate predit model step)
22-Jul-2025 09:35:47 PASSED
22-Jul-2025 09:35:47
22-Jul-2025 09:36:02 test_task_tclean.py::test_modelvis::test_modelvis_13
22-Jul-2025 09:36:02 [modelpredict] Test_modelvis_13 : (CAS-12618) mfs with automask and save virtual model (saving model via a separate predict model step)
22-Jul-2025 09:36:02 PASSED
22-Jul-2025 09:36:02
22-Jul-2025 09:36:18 test_task_tclean.py::test_modelvis::test_modelvis_14
22-Jul-2025 09:36:40 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:36:43
22-Jul-2025 09:36:43 [modelpredict] Test_modelvis_14 : (cas-12618) mfs with nsigma and save model column (saving model via a separate predict model step)
22-Jul-2025 09:36:43 PASSED
22-Jul-2025 09:36:43
22-Jul-2025 09:36:58 test_task_tclean.py::test_modelvis::test_modelvis_15
22-Jul-2025 09:36:58 [modelpredict] Test_modelvis_15 : (CAS-12618) mfs with nsigma and save model column (saving model via a separate predict model step)
22-Jul-2025 09:36:58 PASSED
22-Jul-2025 09:36:58
22-Jul-2025 09:37:00 test_task_tclean.py::test_modelvis::test_modelvis_16 2025-07-22 13:37:00        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:37:20 2025-07-22 13:37:20        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:37:27
22-Jul-2025 09:37:54 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:38:01
22-Jul-2025 09:38:01 [modelpredict] Test_modelvis_16 : (CAS-12618) cube with  and save  model column for auto-multithresh
22-Jul-2025 09:38:02 PASSED
22-Jul-2025 09:38:02
22-Jul-2025 09:38:03 test_task_tclean.py::test_modelvis::test_modelvis_17 2025-07-22 13:38:03        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:38:20 2025-07-22 13:38:20        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:38:28
22-Jul-2025 09:38:28 [modelpredict] Test_modelvis_17 : (CAS-12618) cube with  and save virtual model for auto-multithreseh
22-Jul-2025 09:38:28 PASSED
22-Jul-2025 09:38:28
22-Jul-2025 09:38:29 test_task_tclean.py::test_modelvis::test_modelvis_18 2025-07-22 13:38:29        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:38:46 2025-07-22 13:38:46        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:38:53
22-Jul-2025 09:39:29 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:39:35
22-Jul-2025 09:39:35 [modelpredict] Test_modelvis_18 : (CAS-12618) cube with  and save model column for nsgima >0.0
22-Jul-2025 09:39:35 PASSED
22-Jul-2025 09:39:35
22-Jul-2025 09:39:36 test_task_tclean.py::test_modelvis::test_modelvis_19 2025-07-22 13:39:37        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:39:50 2025-07-22 13:39:50        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:39:59
22-Jul-2025 09:39:59 [modelpredict] Test_modelvis_19 : (CAS-12618) cube with  and save virtual model for nsima >0.0
22-Jul-2025 09:39:59 PASSED
22-Jul-2025 09:39:59
22-Jul-2025 09:40:12 test_task_tclean.py::test_modelvis::test_modelvis_2
22-Jul-2025 09:40:45 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:40:55
22-Jul-2025 09:41:16 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:41:32
22-Jul-2025 09:41:47 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:41:49
22-Jul-2025 09:41:49 [modelpredict] Test_modelvis_2 : mfs with save model column 
22-Jul-2025 09:41:49 PASSED
22-Jul-2025 09:41:49
22-Jul-2025 09:42:12 test_task_tclean.py::test_modelvis::test_modelvis_20
22-Jul-2025 09:42:26 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:42:26
22-Jul-2025 09:42:26 [modelpredict] Test_modelvis_20 : (CAS-13615) mfs with automask and save model column (saving model via a separate niter=0 tclean call)
22-Jul-2025 09:42:26 PASSED
22-Jul-2025 09:42:26
22-Jul-2025 09:42:41 test_task_tclean.py::test_modelvis::test_modelvis_21
22-Jul-2025 09:42:41 [modelpredict] Test_modelvis_21 : (CAS-13615) mfs with automask and save virtual model (saving model via a separate niter=0 tclean call)
22-Jul-2025 09:42:41 PASSED
22-Jul-2025 09:42:41
22-Jul-2025 09:43:01 test_task_tclean.py::test_modelvis::test_modelvis_22
22-Jul-2025 09:43:15 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:43:17
22-Jul-2025 09:43:17 [modelpredict] Test_modelvis_22 : (cas-13615) mfs with nsigma and save model column (saving model via a separate niter=0 tclean call)
22-Jul-2025 09:43:17 PASSED
22-Jul-2025 09:43:17
22-Jul-2025 09:43:41 test_task_tclean.py::test_modelvis::test_modelvis_23
22-Jul-2025 09:43:41 [modelpredict] Test_modelvis_23 : (CAS-13615) mfs with nsigma and save model column (saving model via a separate niterpredict model step)
22-Jul-2025 09:43:41 PASSED
22-Jul-2025 09:43:41
22-Jul-2025 09:43:42 test_task_tclean.py::test_modelvis::test_modelvis_24 2025-07-22 13:43:42        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:44:07 2025-07-22 13:44:08        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:44:16
22-Jul-2025 09:44:55 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:44:57
22-Jul-2025 09:44:57 [modelpredict] Test_modelvis_24 : (CAS-13615) cube with  and save  model column for auto-multithresh (in two steps)
22-Jul-2025 09:44:57 PASSED
22-Jul-2025 09:44:57
22-Jul-2025 09:44:58 test_task_tclean.py::test_modelvis::test_modelvis_25 2025-07-22 13:44:59        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:45:22 2025-07-22 13:45:23        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:45:27
22-Jul-2025 09:45:27 [modelpredict] Test_modelvis_25: (CAS-13615) cube with and save virtual model for auto-multithreseh (in two steps) 
22-Jul-2025 09:45:27 PASSED
22-Jul-2025 09:45:27
22-Jul-2025 09:45:29 test_task_tclean.py::test_modelvis::test_modelvis_26 2025-07-22 13:45:29        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:45:51 2025-07-22 13:45:52        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:46:03
22-Jul-2025 09:46:27 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:46:28
22-Jul-2025 09:46:28 [modelpredict] Test_modelvis_26 : (CAS-13615) cube with and save model column for nsgima >0.0 (in two steps) 
22-Jul-2025 09:46:28 PASSED
22-Jul-2025 09:46:28
22-Jul-2025 09:46:29 test_task_tclean.py::test_modelvis::test_modelvis_27 2025-07-22 13:46:29        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:46:49 2025-07-22 13:46:49        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:46:54
22-Jul-2025 09:46:54 [modelpredict] Test_modelvis_27: (CAS-13615) cube with and save virtual model for nsigma >0.0 (in two steps) 
22-Jul-2025 09:46:54 PASSED
22-Jul-2025 09:46:54
22-Jul-2025 09:46:55 test_task_tclean.py::test_modelvis::test_modelvis_28 2025-07-22 13:46:55        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
22-Jul-2025 09:46:55 2025-07-22 13:46:55        WARN        tclean::::casa        Column WEIGHT_SPECTRUM in table refim_point_onespw0.ms has no data. Accessing it will cause errors.
22-Jul-2025 09:46:55 2025-07-22 13:46:55        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
22-Jul-2025 09:46:55 2025-07-22 13:46:55        WARN        tclean::::casa        Column WEIGHT_SPECTRUM in table refim_point_onespw1.ms has no data. Accessing it will cause errors.
22-Jul-2025 09:47:05
22-Jul-2025 09:47:05 [modelpredict] Test_modelvis_28: (CAS-13925) mfs with two MSes, savemodel=modelcolumn, nsigma > 0.0 
22-Jul-2025 09:47:05 PASSED
22-Jul-2025 09:47:05
22-Jul-2025 09:47:06 test_task_tclean.py::test_modelvis::test_modelvis_29 2025-07-22 13:47:06        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
22-Jul-2025 09:47:06 2025-07-22 13:47:06        WARN        tclean::::casa        Column WEIGHT_SPECTRUM in table refim_point_onespw0.ms has no data. Accessing it will cause errors.
22-Jul-2025 09:47:06 2025-07-22 13:47:07        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
22-Jul-2025 09:47:06 2025-07-22 13:47:07        WARN        tclean::::casa        Column WEIGHT_SPECTRUM in table refim_point_onespw1.ms has no data. Accessing it will cause errors.
22-Jul-2025 09:47:17
22-Jul-2025 09:47:17 [modelpredict] Test_modelvis_29: (CAS-13925) cube with two MSes, savemodel=modelcolumn, nsigma > 0.0 
22-Jul-2025 09:47:17 PASSED
22-Jul-2025 09:47:17
22-Jul-2025 09:47:49 test_task_tclean.py::test_modelvis::test_modelvis_3
22-Jul-2025 09:47:49 [modelpredict] Test_modelvis_3 : mfs with save virtual model 
22-Jul-2025 09:47:49 PASSED
22-Jul-2025 09:47:49
22-Jul-2025 09:48:12 test_task_tclean.py::test_modelvis::test_modelvis_4
22-Jul-2025 09:48:12 [modelpredict] Test_modelvis_4 : mt-mfs with no save model 
22-Jul-2025 09:48:12 PASSED
22-Jul-2025 09:48:12
22-Jul-2025 09:48:28 test_task_tclean.py::test_modelvis::test_modelvis_5
22-Jul-2025 09:48:40 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:48:53
22-Jul-2025 09:49:51 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:50:35
22-Jul-2025 09:50:35 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:50:48
22-Jul-2025 09:52:02 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:52:23
22-Jul-2025 09:52:23 [modelpredict] Test_modelvis_5 : mt-mfs with save model column 
22-Jul-2025 09:52:23 PASSED
22-Jul-2025 09:52:23
22-Jul-2025 09:52:33 test_task_tclean.py::test_modelvis::test_modelvis_6
22-Jul-2025 09:52:33 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:53:20
22-Jul-2025 09:53:20 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:53:42
22-Jul-2025 09:53:42 [modelpredict] Test_modelvis_6 : mt-mfs with save virtual model 
22-Jul-2025 09:53:42 PASSED
22-Jul-2025 09:53:42
22-Jul-2025 09:53:45 test_task_tclean.py::test_modelvis::test_modelvis_7 2025-07-22 13:53:45        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.425e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:53:59
22-Jul-2025 09:55:02 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:55:13
22-Jul-2025 09:55:57 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:56:12 2025-07-22 13:56:12        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:56:26
22-Jul-2025 09:56:53 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:57:01
22-Jul-2025 09:57:01 [modelpredict] Test_modelvis_7 : cube with chan selection and save model column 
22-Jul-2025 09:57:01 [check_chanvals] Chan 3  is (0.9999999999999999+0j) (Pass : should be ==1.0)
22-Jul-2025 09:57:01
22-Jul-2025 09:57:01 [check_chanvals] Chan 3  is (0.7863571717245512+0j) (Pass : should be ==(0.7863571722888284+0j))
22-Jul-2025 09:57:01
22-Jul-2025 09:57:01 PASSED
22-Jul-2025 09:57:01
22-Jul-2025 09:57:02 test_task_tclean.py::test_modelvis::test_modelvis_8 2025-07-22 13:57:02        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.425e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:57:04 2025-07-22 13:57:05        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2136)        PSF is blank for[C0:P0]
22-Jul-2025 09:57:18 2025-07-22 13:57:19        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:57:46
22-Jul-2025 09:57:46 [modelpredict] Test_modelvis_8 : cube with chan selection and save virtual model 
22-Jul-2025 09:57:46 PASSED
22-Jul-2025 09:57:46
22-Jul-2025 09:58:16 test_task_tclean.py::test_modelvis::test_modelvis_9
22-Jul-2025 09:59:06 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:59:36
22-Jul-2025 09:59:36 [modelpredict] Test_modelvis_9 : Don't de-grid channels with zero model. Also test limited-freq mask 
22-Jul-2025 09:59:36 PASSED
22-Jul-2025 09:59:36
22-Jul-2025 09:59:41 test_task_tclean.py::test_startmodel::test_csys_startmodel_restart_cube 2025-07-22 13:59:41        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 09:59:54
22-Jul-2025 09:59:54 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 09:59:59 2025-07-22 14:00:00        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 10:00:07 2025-07-22 14:00:08        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 10:00:32 2025-07-22 14:00:33        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 10:00:41
22-Jul-2025 10:01:32 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 10:01:38 2025-07-22 14:01:39        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 10:01:49 2025-07-22 14:01:50        WARN        task_tclean::SIImageStore::restore (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2345)        Restoring with an empty model image. Only residuals will be processed to form the output restored image.
22-Jul-2025 10:01:58 2025-07-22 14:01:58        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 10:02:20 2025-07-22 14:02:21        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 10:02:31
22-Jul-2025 10:03:07 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 [startmodel] test_csys_startmodel_restart_cube : Check that csys differences w.r.to latpoles for parallel vs serial runs are appropriately squashed.
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 Run a sequence of tclean runs to trigger a complicated situation of restarts, mixing serial/parallel and model writes.
22-Jul-2025 10:03:11 This sequence, coupled with the algorithm options listed below in tests #1 through #6 trigger three different errors that
22-Jul-2025 10:03:11 have been fixed in this branch, and one that will be addressed via CAS-9386 (cube refactor).
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 tclean call sequence :
22-Jul-2025 10:03:11 --- (a) Parallel run for niter=0
22-Jul-2025 10:03:11 --- (b) Serial/Parallel run for niter=10, with calcres=F, calcpsf=F  : to reuse images from prev.
22-Jul-2025 10:03:11 --- (c) Serial model-predict run (without/with startmodel) : in one case it reuses prev image-set. in other case it reuses only 'model'.
22-Jul-2025 10:03:11 --- (d) Impbcor on the output of (b)
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 Note that this is not a full fix of the various instances of the 'latpole' inconsistency, but only a workaround.
22-Jul-2025 10:03:11 Hence it needs a test to ensure this keeps working. 
22-Jul-2025 10:03:11 Run with cube - hogbom - False
22-Jul-2025 10:03:11 Run with cube - hogbom - usestartmodel = False - imaging in parallel
22-Jul-2025 10:03:11 [check_chanvals] Chan 19  is (0.5040750624302417+0j) (Pass : should be <0.9)
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 Run with cube - hogbom - True
22-Jul-2025 10:03:11 Run with cube - hogbom - usestartmodel = True - imaging in parallel
22-Jul-2025 10:03:11 [check_chanvals] Chan 19  is (0.5040750624302417+0j) (Pass : should be <0.9)
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 PASSED
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 test_task_tclean.py::test_startmodel::test_csys_startmodel_restart_mfs
22-Jul-2025 10:03:11 [startmodel] test_csys_startmodel_restart_cube
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 Run a sequence of tclean runs to trigger a complicated situation of restarts, mixing serial/parallel and model writes.
22-Jul-2025 10:03:11 This sequence, coupled with the algorithm options listed below in tests #1 through #6 trigger three different errors that
22-Jul-2025 10:03:11 have been fixed in this branch, and one that will be addressed via CAS-9386 (cube refactor).
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 tclean call sequence :
22-Jul-2025 10:03:11 --- (a) Parallel run for niter=0
22-Jul-2025 10:03:11 --- (b) Serial/Parallel run for niter=10, with calcres=F, calcpsf=F  : to reuse images from prev.
22-Jul-2025 10:03:11 --- (c) Serial model-predict run (without/with startmodel) : in one case it reuses prev image-set. in other case it reuses only 'model'.
22-Jul-2025 10:03:11 --- (d) Impbcor on the output of (b)
22-Jul-2025 10:03:11
22-Jul-2025 10:03:11 Note that this is not a full fix of the various instances of the 'latpole' inconsistency, but only a workaround.
22-Jul-2025 10:03:11 Hence it needs a test to ensure this keeps working. 
22-Jul-2025 10:03:11 SKIPPED (Skip the test temporarily)
22-Jul-2025 10:03:11
22-Jul-2025 10:03:28 test_task_tclean.py::test_startmodel::test_startmodel_mfs_changeshape_1 2025-07-22 14:03:28        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.
22-Jul-2025 10:03:28
22-Jul-2025 10:03:28 [startmodel] test_startmodel_mfs_changeshape_1 : Restart a run but change shape only (cas-6937)
22-Jul-2025 10:03:28 PASSED
22-Jul-2025 10:03:28
22-Jul-2025 10:03:59 test_task_tclean.py::test_startmodel::test_startmodel_mfs_changeshape_2
22-Jul-2025 10:03:59 [startmodel] test_startmodel_mfs_changeshape_2 : Restart a run using 'startmodel' and change shape and imagename
22-Jul-2025 10:03:59 PASSED
22-Jul-2025 10:03:59
22-Jul-2025 10:04:32 test_task_tclean.py::test_startmodel::test_startmodel_mfs_continue
22-Jul-2025 10:04:32 [startmodel] test_startmodel_mfs_continue : Restart a run with no parameter changes
22-Jul-2025 10:04:32 PASSED
22-Jul-2025 10:04:32
22-Jul-2025 10:05:14 test_task_tclean.py::test_startmodel::test_startmodel_mfs_restart
22-Jul-2025 10:05:14 [startmodel] test_startmodel_mfs_restart : Restart a run using 'startmodel' and changed imagename
22-Jul-2025 10:05:14 PASSED
22-Jul-2025 10:05:14
22-Jul-2025 10:05:25 test_task_tclean.py::test_startmodel::test_startmodel_mtmfs_restart
22-Jul-2025 10:05:25 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 10:06:05
22-Jul-2025 10:06:05 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 10:07:25
22-Jul-2025 10:07:25 [startmodel] test_startmodel_mtmfs_restart : Restart a multi-term run using 'startmodel' and changed imagename
22-Jul-2025 10:07:25 PASSED
22-Jul-2025 10:07:25
22-Jul-2025 10:07:26 test_task_tclean.py::test_startmodel::test_startmodel_regrid_cube 2025-07-22 14:07:26        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 10:07:31 2025-07-22 14:07:31        WARN        task_tclean::SIImageStore::getPSFGaussian (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SIImageStore.cc, line 2136)        PSF is blank for[C18:P0] [C19:P0]
22-Jul-2025 10:07:53 2025-07-22 14:07:54        WARN        SynthesisParamsImage::buildCoordinateSystem (file /source/casa6/casatools/src/code/synthesis/ImagerObjects/SynthesisUtilMethods.cc, line 2849)        No rest frequency info, using the center of the selected spw(s):1.475e+09 Hz. Velocity labelling may not be correct.
22-Jul-2025 10:08:21
22-Jul-2025 10:08:21 [modelpredict] Test_startmodel_regrid_cube : Regrid input model onto new image grid : cube (ra/dec/specframe)
22-Jul-2025 10:08:21 PASSED
22-Jul-2025 10:08:21
22-Jul-2025 10:08:42 test_task_tclean.py::test_startmodel::test_startmodel_regrid_mfs
22-Jul-2025 10:08:42 [modelpredict] Test_startmodel_regrid_mfs : Regrid input model onto new image grid : mfs (ra/dec) 
22-Jul-2025 10:08:42 PASSED
22-Jul-2025 10:08:42
22-Jul-2025 10:09:15 test_task_tclean.py::test_startmodel::test_startmodel_with_mask_mfs
22-Jul-2025 10:09:29 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 10:09:56
22-Jul-2025 10:09:56 [startmodel] test_startmodel_with_mask_mfs : Mask out some regions in the startmodel, before prediction 
22-Jul-2025 10:09:56 PASSED
22-Jul-2025 10:09:56
22-Jul-2025 10:10:56 test_task_tclean.py::test_startmodel::test_startmodel_with_mask_mtmfs
22-Jul-2025 10:11:06 0%....10....20....30....40....50....60....70....80....90....100%
22-Jul-2025 10:11:26 Failing task since return code of [/export/home/cbt-el7-11/bamboohome/temp/CASASPECS-ML234MASTERTESTPY312-TTAR9P-27-ScriptBuildTask-15836480257892039665.sh] was 143 while expected 0
22-Jul-2025 10:11:26 Finished task 'Test wheel' with result: Failed
22-Jul-2025 10:11:26 Running post build plugin 'Docker Container Cleanup'
22-Jul-2025 10:11:26 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
22-Jul-2025 10:11:26 Attempting to generate stack trace and terminate spawned sub-processes of process id: 5437
22-Jul-2025 10:11:26
Beginning to execute external process for build 'CASASPECS - ML234 Master Test 3.12 - Test Tasks Many Linux 2.34 Rocky 9.5 Py3.12 #27 (CASASPECS-ML234MASTERTESTPY312-TTAR9P-27)'
... running command line:
/bin/docker rm -f wheel-container-test
... in: /export/home/cbt-el7-11/bamboohome/xml-data/build-dir/CASASPECS-ML234MASTERTESTPY312-TTAR9P
22-Jul-2025 10:11:26 Found related process: pid: 9095 ppid: 1 pgid: 5417 %cpu: 0.0 %mem: 0.0 cmd: docker
22-Jul-2025 10:11:26 getStackTraceAndKillRelatedProcesses for 1 processes
22-Jul-2025 10:11:26 Executing kill -3 9095
22-Jul-2025 10:11:31 Killing: 9095
22-Jul-2025 10:11:31 Executing kill 9095
22-Jul-2025 10:11:38 Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.
22-Jul-2025 10:11:38 Has finished generating stack trace and terminating spawned sub-processes of process id: 5437
22-Jul-2025 10:11:38 Exit code: 143, output: STDOUT:  Beginning to execute external process for build 'CASASPECS - ML234 Master Test 3.12 - Test Tasks Many Linux 2.34 Rocky 9.5 Py3.12 #27 (CASASPECS-ML234MASTERTESTPY312-TTAR9P-27)'\n ... running command line: \n/bin/docker rm -f wheel-container-test\n ... in: /export/home/cbt-el7-11/bamboohome/xml-data/build-dir/CASASPECS-ML234MASTERTESTPY312-TTAR9P\nSTDOUT:  Found related process: pid: 9095 ppid: 1 pgid: 5417 %cpu: 0.0 %mem: 0.0 cmd: dockerSTDOUT:  getStackTraceAndKillRelatedProcesses for 1 processesSTDOUT:  Executing kill -3 9095STDOUT:  Killing: 9095STDOUT:  Executing kill 9095STDOUT:  Force Stop build feature is enabled for current plan. Either Bamboo has detected the build has hung or it has been manually stopped.STDOUT:  Has finished generating stack trace and terminating spawned sub-processes of process id: 5437
22-Jul-2025 10:11:38 Failed to execute plugin 'Docker Container Cleanup' with error: Error running Docker remove command
22-Jul-2025 10:11:38 Running post build plugin 'NCover Results Collector'
22-Jul-2025 10:11:38 Running post build plugin 'Build Results Label Collector'
22-Jul-2025 10:11:38 Running post build plugin 'Clover Results Collector'
22-Jul-2025 10:11:38 Running post build plugin 'npm Cache Cleanup'
22-Jul-2025 10:11:38 Running post build plugin 'Artifact Copier'
22-Jul-2025 10:11:38 Publishing an artifact: Workdir
22-Jul-2025 10:11:38 Failing as no matching files has been found and empty artifacts are not allowed.
22-Jul-2025 10:11:38 Unable to publish artifact [Workdir]:
22-Jul-2025 10:11:38 The artifact hasn't been successfully published after 166.4 ms
22-Jul-2025 10:11:38 Publishing an artifact: requirements.txt
22-Jul-2025 10:11:38 Failing as no matching files has been found and empty artifacts are not allowed.
22-Jul-2025 10:11:38 Unable to publish artifact [requirements.txt]:
22-Jul-2025 10:11:38 The artifact hasn't been successfully published after 21.83 ms
22-Jul-2025 10:11:38 Publishing an artifact: XML Directories
22-Jul-2025 10:11:38 Failing as no matching files has been found and empty artifacts are not allowed.
22-Jul-2025 10:11:38 Unable to publish artifact [XML Directories]:
22-Jul-2025 10:11:38 The artifact hasn't been successfully published after 19.21 ms
22-Jul-2025 10:11:38 Finalising the build...
22-Jul-2025 10:11:38 Stopping timer.
22-Jul-2025 10:11:38 Build CASASPECS-ML234MASTERTESTPY312-TTAR9P-27 completed.
22-Jul-2025 10:11:39 Running on server: post build plugin 'Build Hanging Detection Configuration'
22-Jul-2025 10:11:39 Running on server: post build plugin 'NCover Results Collector'
22-Jul-2025 10:11:39 Running on server: post build plugin 'Build Labeller'
22-Jul-2025 10:11:39 Running on server: post build plugin 'Clover Delta Calculator'
22-Jul-2025 10:11:39 Running on server: post build plugin 'Maven Dependencies Postprocessor'
22-Jul-2025 10:11:39 All post build plugins have finished
22-Jul-2025 10:11:39 Generating build results summary...
22-Jul-2025 10:11:39 Saving build results to disk...
22-Jul-2025 10:11:39 Store variable context...
22-Jul-2025 10:11:39 Finished building CASASPECS-ML234MASTERTESTPY312-TTAR9P-27.